builder: fx-team_win7_ix_test-dromaeojs-pgo
slave: t-w732-ix-070
starttime: 1477097637.98
results: success (0)
buildid: 20161021175351
builduid: 6cb69a079b28495fafdd31dcd4b4f28f
revision: 5639a9f476d08f300c079117e61697f5026b6367
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-21 17:53:57.984463) =========
master: http://buildbot-master112.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-21 17:53:57.984948) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-21 17:53:57.985226) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-21 17:53:58.162413) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-21 17:53:58.162730) =========
'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-070
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-070
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-070
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
--17:53:58-- 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% 25.87 MB/s
17:53:58 (25.87 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=1.190000
========= master_lag: 0.63 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-21 17:53:59.979636) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 12 secs) (at 2016-10-21 17:53:59.979951) =========
'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-070
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-070
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-070
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=11.209000
========= master_lag: 1.78 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 12 secs) (at 2016-10-21 17:54:12.965427) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-21 17:54:12.965728) =========
'bash' '-c' u'python archiver_client.py mozharness --repo integration/fx-team --rev 5639a9f476d08f300c079117e61697f5026b6367 --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 5639a9f476d08f300c079117e61697f5026b6367 --destination scripts --debug']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-070
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-070
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-070
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-21 17:54:13,095 truncating revision to first 12 chars
2016-10-21 17:54:13,095 Setting DEBUG logging.
2016-10-21 17:54:13,095 attempt 1/10
2016-10-21 17:54:13,096 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/5639a9f476d0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2016-10-21 17:54:13,612 unpacking tar archive at: fx-team-5639a9f476d0/testing/mozharness/
2016-10-21 17:54:13,734 skipping symlink on windows: configs/single_locale/linux32.py
program finished with exit code 0
elapsedTime=1.298000
========= master_lag: 0.11 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-21 17:54:14.377054) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-21 17:54:14.377415) =========
script_repo_revision: 5639a9f476d08f300c079117e61697f5026b6367
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-21 17:54:14.377794) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-21 17:54:14.378064) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-21 17:54:14.387954) =========
========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 0 secs) (at 2016-10-21 17:54:14.388238) =========
'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-070
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-070
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-070
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
17:54:14 INFO - MultiFileLogger online at 20161021 17:54:14 in C:\slave\test
17:54:14 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
17:54:14 INFO - Dumping config to C:\slave\test\logs\localconfig.json.
17:54:14 INFO - {'append_to_log': False,
17:54:14 INFO - 'base_work_dir': 'C:\\slave\\test',
17:54:14 INFO - 'blob_upload_branch': 'Fx-Team',
17:54:14 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt',
17:54:14 INFO - 'branch': 'Fx-Team',
17:54:14 INFO - 'buildbot_json_path': 'buildprops.json',
17:54:14 INFO - 'config_files': ('talos/windows_config.py',),
17:54:14 INFO - 'default_actions': ('clobber',
17:54:14 INFO - 'read-buildbot-config',
17:54:14 INFO - 'download-and-extract',
17:54:14 INFO - 'populate-webroot',
17:54:14 INFO - 'create-virtualenv',
17:54:14 INFO - 'install',
17:54:14 INFO - 'run-tests'),
17:54:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
17:54:14 INFO - 'download_minidump_stackwalk': True,
17:54:14 INFO - 'download_symbols': 'ondemand',
17:54:14 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python',
17:54:14 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'),
17:54:14 INFO - 'hg': 'c:/mozilla-build/hg/hg',
17:54:14 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python',
17:54:14 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'),
17:54:14 INFO - 'python': 'c:/mozilla-build/python27/python.exe',
17:54:14 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe',
17:54:14 INFO - 'C:/mozilla-build/tooltool.py'),
17:54:14 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe',
17:54:14 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')},
17:54:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
17:54:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
17:54:14 INFO - 'installer_path': 'installer.exe',
17:54:14 INFO - 'log_level': 'info',
17:54:14 INFO - 'log_name': 'talos',
17:54:14 INFO - 'log_to_console': True,
17:54:14 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe',
17:54:14 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe',
17:54:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest',
17:54:14 INFO - 'opt_config_files': (),
17:54:14 INFO - 'pip_index': False,
17:54:14 INFO - 'sps_profile': False,
17:54:14 INFO - 'sps_profile_interval': 0,
17:54:14 INFO - 'suite': 'dromaeojs',
17:54:14 INFO - 'system_bits': '32',
17:54:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
17:54:14 INFO - 'title': 'T-W732-IX-070',
17:54:14 INFO - 'use_talos_json': True,
17:54:14 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'),
17:54:14 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv',
17:54:14 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll',
17:54:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
17:54:14 INFO - 'work_dir': 'build'}
17:54:14 INFO - [mozharness: 2016-10-22 00:54:14.421000Z] Running clobber step.
17:54:14 INFO - Running pre-action listener: _resource_record_pre_action
17:54:14 INFO - Running main action method: clobber
17:54:14 INFO - rmtree: C:\slave\test\build
17:54:14 INFO - Using _rmtree_windows ...
17:54:14 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1
17:55:33 INFO - Running post-action listener: _resource_record_post_action
17:55:33 INFO - [mozharness: 2016-10-22 00:55:33.307000Z] Finished clobber step (success)
17:55:33 INFO - [mozharness: 2016-10-22 00:55:33.307000Z] Running read-buildbot-config step.
17:55:33 INFO - Running pre-action listener: _resource_record_pre_action
17:55:33 INFO - Running main action method: read_buildbot_config
17:55:33 INFO - Using buildbot properties:
17:55:33 INFO - {
17:55:33 INFO - "project": "",
17:55:33 INFO - "product": "firefox",
17:55:33 INFO - "script_repo_revision": "production",
17:55:33 INFO - "builddir": "fx-team_win7_ix_test-dromaeojs",
17:55:33 INFO - "repository": "",
17:55:33 INFO - "buildername": "Windows 7 32-bit fx-team pgo talos dromaeojs",
17:55:33 INFO - "buildid": "20161021175351",
17:55:33 INFO - "stage_platform": "win32-pgo",
17:55:33 INFO - "basedir": "C:\\slave\\test",
17:55:33 INFO - "buildnumber": 180,
17:55:33 INFO - "platform": "win7_ix",
17:55:33 INFO - "master": "http://buildbot-master112.bb.releng.scl3.mozilla.com:8201/",
17:55:33 INFO - "slavebuilddir": "test",
17:55:33 INFO - "branch": "fx-team",
17:55:33 INFO - "revision": "5639a9f476d08f300c079117e61697f5026b6367",
17:55:33 INFO - "slavename": "t-w732-ix-070",
17:55:33 INFO - "builduid": "6cb69a079b28495fafdd31dcd4b4f28f",
17:55:33 INFO - "repo_path": "integration/fx-team"
17:55:33 INFO - }
17:55:33 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477089004/firefox-52.0a1.en-US.win32.test_packages.json.
17:55:33 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477089004/firefox-52.0a1.en-US.win32.zip.
17:55:33 INFO - Running post-action listener: _resource_record_post_action
17:55:33 INFO - [mozharness: 2016-10-22 00:55:33.310000Z] Finished read-buildbot-config step (success)
17:55:33 INFO - [mozharness: 2016-10-22 00:55:33.311000Z] Running download-and-extract step.
17:55:33 INFO - Running pre-action listener: _resource_record_pre_action
17:55:33 INFO - Running main action method: download_and_extract
17:55:33 INFO - mkdir: C:\slave\test\build\tests
17:55:33 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')]}
17:55:33 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477089004/firefox-52.0a1.en-US.win32.test_packages.json
17:55:33 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477089004/firefox-52.0a1.en-US.win32.test_packages.json to C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json
17:55:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477089004/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
17:55:33 INFO - Downloaded 1218 bytes.
17:55:33 INFO - Reading from file C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json
17:55:33 INFO - Using the following test package requirements:
17:55:33 INFO - {u'common': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
17:55:33 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
17:55:33 INFO - u'firefox-52.0a1.en-US.win32.cppunittest.tests.zip'],
17:55:33 INFO - u'gtest': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
17:55:33 INFO - u'jittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
17:55:33 INFO - u'jsshell-win32.zip'],
17:55:33 INFO - u'mochitest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
17:55:33 INFO - u'firefox-52.0a1.en-US.win32.mochitest.tests.zip'],
17:55:33 INFO - u'mozbase': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
17:55:33 INFO - u'reftest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
17:55:33 INFO - u'firefox-52.0a1.en-US.win32.reftest.tests.zip'],
17:55:33 INFO - u'talos': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
17:55:33 INFO - u'firefox-52.0a1.en-US.win32.talos.tests.zip'],
17:55:33 INFO - u'web-platform': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
17:55:33 INFO - u'firefox-52.0a1.en-US.win32.web-platform.tests.zip'],
17:55:33 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
17:55:33 INFO - u'firefox-52.0a1.en-US.win32.xpcshell.tests.zip']}
17:55:33 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip'] for test suite category: common
17:55:33 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/1477089004/firefox-52.0a1.en-US.win32.common.tests.zip
17:55:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477089004/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1
17:55:33 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477089004/firefox-52.0a1.en-US.win32.common.tests.zip into memory
17:55:33 INFO - Http code: 200
17:55:33 INFO - via: 1.1 5ed093c37d643652e278a198e0556a40.cloudfront.net (CloudFront)
17:55:33 INFO - x-amz-cf-id: 6tNdgndB_nq4imVqxEMe5hG5lk1lhDivwBeQjWe3rj-q2qNJVUCQWw==
17:55:33 INFO - x-amz-replication-status: COMPLETED
17:55:33 INFO - x-amz-version-id: BS8LrdacBwTN52yaCGdMLUbpmOMTwXId
17:55:34 INFO - Expected file size: 17179047
17:55:34 INFO - Obtained file size: 17179047
17:55:58 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
17:55:58 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/1477089004/firefox-52.0a1.en-US.win32.common.tests.zip
17:55:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477089004/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1
17:55:58 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477089004/firefox-52.0a1.en-US.win32.common.tests.zip into memory
17:55:58 INFO - Http code: 200
17:55:58 INFO - via: 1.1 bb8a7ff26b131d51953dc2d3e19a2751.cloudfront.net (CloudFront)
17:55:58 INFO - x-amz-cf-id: 0J5zLFFjX2S3f_Qq4sQ6EP39aloJuxSn2vb7DorvhFNQQdAdMPGD8g==
17:55:58 INFO - x-amz-replication-status: COMPLETED
17:55:58 INFO - x-amz-version-id: BS8LrdacBwTN52yaCGdMLUbpmOMTwXId
17:55:58 INFO - Expected file size: 17179047
17:55:58 INFO - Obtained file size: 17179047
17:56:07 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/1477089004/firefox-52.0a1.en-US.win32.talos.tests.zip
17:56:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477089004/firefox-52.0a1.en-US.win32.talos.tests.zip'}, attempt #1
17:56:07 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477089004/firefox-52.0a1.en-US.win32.talos.tests.zip into memory
17:56:07 INFO - Http code: 200
17:56:07 INFO - via: 1.1 11dd60d1f68e8258294f92935b53a91f.cloudfront.net (CloudFront)
17:56:07 INFO - x-amz-cf-id: NedToKJ6Jg8QvfKLSoyvdAyUGz_FGi9lhjeE3ItEzTXcCGubQtg7mw==
17:56:07 INFO - x-amz-replication-status: COMPLETED
17:56:07 INFO - x-amz-version-id: P_4jTQvHZx7s_qmd8oaiZnE1Sg1BJj7d
17:56:08 INFO - Expected file size: 14409028
17:56:08 INFO - Obtained file size: 14409028
17:56: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')]}
17:56:09 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477089004/firefox-52.0a1.en-US.win32.zip
17:56:09 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477089004/firefox-52.0a1.en-US.win32.zip to C:\slave\test\build\firefox-52.0a1.en-US.win32.zip
17:56:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477089004/firefox-52.0a1.en-US.win32.zip', 'file_name': 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.zip'}, attempt #1
17:56:12 INFO - Downloaded 54237779 bytes.
17:56:12 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477089004/firefox-52.0a1.en-US.win32.zip
17:56:12 INFO - mkdir: C:\slave\test\properties
17:56:12 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url
17:56:12 INFO - Writing to file C:\slave\test\properties\build_url
17:56:12 INFO - Contents:
17:56:12 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477089004/firefox-52.0a1.en-US.win32.zip
17:56:13 INFO - Running post-action listener: _resource_record_post_action
17:56:13 INFO - Running post-action listener: set_extra_try_arguments
17:56:13 INFO - [mozharness: 2016-10-22 00:56:13.190000Z] Finished download-and-extract step (success)
17:56:13 INFO - [mozharness: 2016-10-22 00:56:13.190000Z] Running populate-webroot step.
17:56:13 INFO - Running pre-action listener: _resource_record_pre_action
17:56:13 INFO - Running main action method: populate_webroot
17:56:13 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']},
17:56:13 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']},
17:56:13 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome',
17:56:13 INFO - u'--tppagecycles',
17:56:13 INFO - u'7'],
17:56:13 INFO - u'tests': [u'tsvgm']}},
17:56:13 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'],
17:56:13 INFO - u'tests': [u'tresize', u'tcanvasmark']},
17:56:13 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']},
17:56:13 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'],
17:56:13 INFO - u'tests': [u'dromaeo_css', u'kraken']},
17:56:13 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']},
17:56:13 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
17:56:13 INFO - u'talos_options': [u'--disable-e10s'],
17:56:13 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
17:56:13 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
17:56:13 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
17:56:13 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
17:56:13 INFO - u'talos_options': [u'--disable-e10s'],
17:56:13 INFO - u'tests': [u'damp', u'tps']},
17:56:13 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
17:56:13 INFO - u'tests': [u'damp', u'tps']},
17:56:13 INFO - u'g3': {u'talos_options': [u'--disable-e10s'],
17:56:13 INFO - u'tests': [u'dromaeo_dom']},
17:56:13 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']},
17:56:13 INFO - u'g4': {u'talos_options': [u'--disable-e10s'],
17:56:13 INFO - u'tests': [u'basic_compositor_video']},
17:56:13 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']},
17:56:13 INFO - u'other': {u'talos_options': [u'--disable-e10s'],
17:56:13 INFO - u'tests': [u'a11yr',
17:56:13 INFO - u'ts_paint',
17:56:13 INFO - u'tpaint',
17:56:13 INFO - u'sessionrestore',
17:56:13 INFO - u'sessionrestore_no_auto_restore',
17:56:13 INFO - u'tabpaint']},
17:56:13 INFO - u'other-e10s': {u'tests': [u'a11yr',
17:56:13 INFO - u'ts_paint',
17:56:13 INFO - u'tpaint',
17:56:13 INFO - u'sessionrestore',
17:56:13 INFO - u'sessionrestore_no_auto_restore',
17:56:13 INFO - u'tabpaint']},
17:56:13 INFO - u'other-e10s_l64': {u'tests': [u'a11yr',
17:56:13 INFO - u'ts_paint',
17:56:13 INFO - u'tpaint',
17:56:13 INFO - u'sessionrestore',
17:56:13 INFO - u'sessionrestore_no_auto_restore',
17:56:13 INFO - u'tabpaint']},
17:56:13 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr',
17:56:13 INFO - u'ts_paint',
17:56:13 INFO - u'tpaint',
17:56:13 INFO - u'sessionrestore',
17:56:13 INFO - u'sessionrestore_no_auto_restore',
17:56:13 INFO - u'tabpaint']},
17:56:13 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'],
17:56:13 INFO - u'tests': [u'a11yr',
17:56:13 INFO - u'ts_paint',
17:56:13 INFO - u'tpaint',
17:56:13 INFO - u'sessionrestore',
17:56:13 INFO - u'sessionrestore_no_auto_restore',
17:56:13 INFO - u'tabpaint']},
17:56:13 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'],
17:56:13 INFO - u'tests': [u'a11yr',
17:56:13 INFO - u'ts_paint',
17:56:13 INFO - u'tpaint',
17:56:13 INFO - u'sessionrestore',
17:56:13 INFO - u'sessionrestore_no_auto_restore',
17:56:13 INFO - u'tabpaint']},
17:56:13 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'],
17:56:13 INFO - u'tests': [u'tsvgx',
17:56:13 INFO - u'tsvgr_opacity',
17:56:13 INFO - u'tart',
17:56:13 INFO - u'tscrollx',
17:56:13 INFO - u'cart']},
17:56:13 INFO - u'svgr-e10s': {u'tests': [u'tsvgx',
17:56:13 INFO - u'tsvgr_opacity',
17:56:13 INFO - u'tart',
17:56:13 INFO - u'tscrollx',
17:56:13 INFO - u'cart']},
17:56:13 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
17:56:13 INFO - u'talos_options': [u'--disable-e10s'],
17:56:13 INFO - u'tests': [u'tp5o']},
17:56:13 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
17:56:13 INFO - u'tests': [u'tp5o']},
17:56:13 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
17:56:13 INFO - u'talos_options': [u'--disable-e10s',
17:56:13 INFO - u'--xperf_path',
17:56:13 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
17:56:13 INFO - u'tests': [u'tp5n']},
17:56:13 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
17:56:13 INFO - u'talos_options': [u'--xperf_path',
17:56:13 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
17:56:13 INFO - u'tests': [u'tp5n']}},
17:56:13 INFO - u'talos.zip': {u'path': u'',
17:56:13 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}}
17:56:13 INFO - Running post-action listener: _resource_record_post_action
17:56:13 INFO - [mozharness: 2016-10-22 00:56:13.239000Z] Finished populate-webroot step (success)
17:56:13 INFO - [mozharness: 2016-10-22 00:56:13.239000Z] Running create-virtualenv step.
17:56:13 INFO - Running pre-action listener: _resource_record_pre_action
17:56:13 INFO - Running main action method: create_virtualenv
17:56:13 INFO - Creating virtualenv C:\slave\test\build/venv
17:56:13 INFO - mkdir: C:\slave\test\build\venv\Scripts
17:56:13 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll
17:56:13 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'!
17:56:13 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
17:56:13 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv
17:56:23 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe
17:56:27 INFO - Installing distribute......................................................................................................................................................................................done.
17:56:30 INFO - Return code: 0
17:56:30 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version']
17:56:30 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version
17:56:30 INFO - Reading from file tmpfile_stdout
17:56:30 INFO - Output received:
17:56:30 INFO - pip 0.8.2 from c:\slave\test\build\venv\lib\site-packages\pip-0.8.2-py2.7.egg (python 2.7)
17:56:30 INFO - Using _rmtree_windows ...
17:56:30 INFO - Using _rmtree_windows ...
17:56:30 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv
17:56: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')]}
17:56:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
17:56: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')]}
17:56:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
17:56:30 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 0x019BB640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01A36E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01932B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01A10E90>, '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 0x0130A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F25520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-070', 'USERDOMAIN': 'T-W732-IX-070', '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-070', '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
17:56:30 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
17:56:30 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
17:56:30 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
17:56:30 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
17:56:30 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
17:56:30 INFO - 'COMPUTERNAME': 'T-W732-IX-070',
17:56:30 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
17:56:30 INFO - 'CYGWINBASE': 'C:\\cygwin',
17:56:30 INFO - 'DCLOCATION': 'SCL3',
17:56:30 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
17:56:30 INFO - 'FP_NO_HOST_CHECK': 'NO',
17:56:30 INFO - 'HOMEDRIVE': 'C:',
17:56:30 INFO - 'HOMEPATH': '\\Users\\cltbld',
17:56:30 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
17:56:30 INFO - 'KTS_VERSION': '1.19c',
17:56:30 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
17:56:30 INFO - 'LOGONSERVER': '\\\\T-W732-IX-070',
17:56:30 INFO - 'MONDIR': 'C:\\Monitor_config\\',
17:56:30 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
17:56:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
17:56:30 INFO - 'MOZ_NO_REMOTE': '1',
17:56:30 INFO - 'NO_EM_RESTART': '1',
17:56:30 INFO - 'NUMBER_OF_PROCESSORS': '8',
17:56:30 INFO - 'OS': 'Windows_NT',
17:56:30 INFO - 'OURDRIVE': 'C:',
17:56: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;',
17:56:30 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
17:56:30 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
17:56:30 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
17:56:30 INFO - 'PROCESSOR_LEVEL': '6',
17:56:30 INFO - 'PROCESSOR_REVISION': '1e05',
17:56:30 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
17:56:30 INFO - 'PROGRAMFILES': 'C:\\Program Files',
17:56:30 INFO - 'PROMPT': '$P$G',
17:56:30 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
17:56:30 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
17:56:30 INFO - 'PUBLIC': 'C:\\Users\\Public',
17:56:30 INFO - 'PWD': 'C:\\slave\\test',
17:56:30 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
17:56:30 INFO - 'SLAVEDIR': 'C:\\slave\\',
17:56:30 INFO - 'SYSTEMDRIVE': 'C:',
17:56:30 INFO - 'SYSTEMROOT': 'C:\\windows',
17:56:30 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
17:56:30 INFO - 'TEST1': 'testie',
17:56:30 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
17:56:30 INFO - 'USERDOMAIN': 'T-W732-IX-070',
17:56:30 INFO - 'USERNAME': 'cltbld',
17:56:30 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
17:56:30 INFO - 'WINDIR': 'C:\\windows',
17:56:30 INFO - 'WINDOWS_TRACING_FLAGS': '3',
17:56:30 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
17:56:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
17:56:31 INFO - Ignoring indexes: http://pypi.python.org/simple/
17:56:31 INFO - Downloading/unpacking pip>=1.5
17:56:35 INFO - Running setup.py egg_info for package pip
17:56:35 INFO - warning: no files found matching 'pip\cacert.pem'
17:56:35 INFO - warning: no files found matching '*.html' under directory 'docs'
17:56:35 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
17:56:35 INFO - no previously-included directories found matching 'docs\_build\_sources'
17:56:35 INFO - Installing collected packages: pip
17:56:35 INFO - Found existing installation: pip 0.8.2
17:56:35 INFO - Uninstalling pip:
17:56:35 INFO - Successfully uninstalled pip
17:56:35 INFO - Running setup.py install for pip
17:56:35 INFO - warning: no files found matching 'pip\cacert.pem'
17:56:35 INFO - warning: no files found matching '*.html' under directory 'docs'
17:56:35 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
17:56:35 INFO - no previously-included directories found matching 'docs\_build\_sources'
17:56:35 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts
17:56:35 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts
17:56:35 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts
17:56:35 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts
17:56:35 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts
17:56:35 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts
17:56:35 INFO - Successfully installed pip
17:56:35 INFO - Cleaning up...
17:56:35 INFO - Return code: 0
17:56:35 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv
17:56:35 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')]}
17:56:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
17:56:35 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')]}
17:56:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
17:56:35 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 0x019BB640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01A36E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01932B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01A10E90>, '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 0x0130A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F25520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-070', 'USERDOMAIN': 'T-W732-IX-070', '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-070', '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
17:56:35 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
17:56:35 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
17:56:35 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
17:56:35 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
17:56:35 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
17:56:35 INFO - 'COMPUTERNAME': 'T-W732-IX-070',
17:56:35 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
17:56:35 INFO - 'CYGWINBASE': 'C:\\cygwin',
17:56:35 INFO - 'DCLOCATION': 'SCL3',
17:56:35 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
17:56:35 INFO - 'FP_NO_HOST_CHECK': 'NO',
17:56:35 INFO - 'HOMEDRIVE': 'C:',
17:56:35 INFO - 'HOMEPATH': '\\Users\\cltbld',
17:56:35 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
17:56:35 INFO - 'KTS_VERSION': '1.19c',
17:56:35 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
17:56:35 INFO - 'LOGONSERVER': '\\\\T-W732-IX-070',
17:56:35 INFO - 'MONDIR': 'C:\\Monitor_config\\',
17:56:35 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
17:56:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
17:56:35 INFO - 'MOZ_NO_REMOTE': '1',
17:56:35 INFO - 'NO_EM_RESTART': '1',
17:56:35 INFO - 'NUMBER_OF_PROCESSORS': '8',
17:56:35 INFO - 'OS': 'Windows_NT',
17:56:35 INFO - 'OURDRIVE': 'C:',
17:56:35 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;',
17:56:35 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
17:56:35 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
17:56:35 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
17:56:35 INFO - 'PROCESSOR_LEVEL': '6',
17:56:35 INFO - 'PROCESSOR_REVISION': '1e05',
17:56:35 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
17:56:35 INFO - 'PROGRAMFILES': 'C:\\Program Files',
17:56:35 INFO - 'PROMPT': '$P$G',
17:56:35 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
17:56:35 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
17:56:35 INFO - 'PUBLIC': 'C:\\Users\\Public',
17:56:35 INFO - 'PWD': 'C:\\slave\\test',
17:56:35 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
17:56:35 INFO - 'SLAVEDIR': 'C:\\slave\\',
17:56:35 INFO - 'SYSTEMDRIVE': 'C:',
17:56:35 INFO - 'SYSTEMROOT': 'C:\\windows',
17:56:35 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
17:56:35 INFO - 'TEST1': 'testie',
17:56:35 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
17:56:35 INFO - 'USERDOMAIN': 'T-W732-IX-070',
17:56:35 INFO - 'USERNAME': 'cltbld',
17:56:35 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
17:56:35 INFO - 'WINDIR': 'C:\\windows',
17:56:35 INFO - 'WINDOWS_TRACING_FLAGS': '3',
17:56:35 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
17:56:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
17:56:37 INFO - Ignoring indexes: https://pypi.python.org/simple/
17:56:37 INFO - Downloading/unpacking psutil>=3.1.1
17:56:37 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
17:56:37 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
17:56:38 INFO - Installing collected packages: psutil
17:56:38 INFO - Successfully installed psutil
17:56:38 INFO - Cleaning up...
17:56:38 INFO - Return code: 0
17:56:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv
17:56: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')]}
17:56:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
17:56: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')]}
17:56:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
17:56: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x019BB640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01A36E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01932B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01A10E90>, '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 0x0130A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F25520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-070', 'USERDOMAIN': 'T-W732-IX-070', '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-070', '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
17:56: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', 'mozsystemmonitor==0.3'] in C:\slave\test\build
17:56: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 mozsystemmonitor==0.3
17:56:38 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
17:56:38 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
17:56:38 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
17:56:38 INFO - 'COMPUTERNAME': 'T-W732-IX-070',
17:56:38 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
17:56:38 INFO - 'CYGWINBASE': 'C:\\cygwin',
17:56:38 INFO - 'DCLOCATION': 'SCL3',
17:56:38 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
17:56:38 INFO - 'FP_NO_HOST_CHECK': 'NO',
17:56:38 INFO - 'HOMEDRIVE': 'C:',
17:56:38 INFO - 'HOMEPATH': '\\Users\\cltbld',
17:56:38 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
17:56:38 INFO - 'KTS_VERSION': '1.19c',
17:56:38 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
17:56:38 INFO - 'LOGONSERVER': '\\\\T-W732-IX-070',
17:56:38 INFO - 'MONDIR': 'C:\\Monitor_config\\',
17:56:38 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
17:56:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
17:56:38 INFO - 'MOZ_NO_REMOTE': '1',
17:56:38 INFO - 'NO_EM_RESTART': '1',
17:56:38 INFO - 'NUMBER_OF_PROCESSORS': '8',
17:56:38 INFO - 'OS': 'Windows_NT',
17:56:38 INFO - 'OURDRIVE': 'C:',
17:56: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;',
17:56:38 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
17:56:38 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
17:56:38 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
17:56:38 INFO - 'PROCESSOR_LEVEL': '6',
17:56:38 INFO - 'PROCESSOR_REVISION': '1e05',
17:56:38 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
17:56:38 INFO - 'PROGRAMFILES': 'C:\\Program Files',
17:56:38 INFO - 'PROMPT': '$P$G',
17:56:38 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
17:56:38 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
17:56:38 INFO - 'PUBLIC': 'C:\\Users\\Public',
17:56:38 INFO - 'PWD': 'C:\\slave\\test',
17:56:38 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
17:56:38 INFO - 'SLAVEDIR': 'C:\\slave\\',
17:56:38 INFO - 'SYSTEMDRIVE': 'C:',
17:56:38 INFO - 'SYSTEMROOT': 'C:\\windows',
17:56:38 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
17:56:38 INFO - 'TEST1': 'testie',
17:56:38 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
17:56:38 INFO - 'USERDOMAIN': 'T-W732-IX-070',
17:56:38 INFO - 'USERNAME': 'cltbld',
17:56:38 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
17:56:38 INFO - 'WINDIR': 'C:\\windows',
17:56:38 INFO - 'WINDOWS_TRACING_FLAGS': '3',
17:56:38 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
17:56:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
17:56:38 INFO - Ignoring indexes: https://pypi.python.org/simple/
17:56:38 INFO - Downloading/unpacking mozsystemmonitor==0.3
17:56:38 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
17:56:38 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
17:56:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz
17:56:39 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor
17:56:40 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)
17:56:40 INFO - Installing collected packages: mozsystemmonitor
17:56:40 INFO - Running setup.py install for mozsystemmonitor
17:56:40 INFO - Successfully installed mozsystemmonitor
17:56:40 INFO - Cleaning up...
17:56:40 INFO - Return code: 0
17:56:40 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv
17:56:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
17:56:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
17:56:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
17:56:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
17:56:40 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 0x019BB640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01A36E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01932B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01A10E90>, '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 0x0130A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F25520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-070', 'USERDOMAIN': 'T-W732-IX-070', '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-070', '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
17:56:40 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
17:56:40 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
17:56:40 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
17:56:40 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
17:56:40 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
17:56:40 INFO - 'COMPUTERNAME': 'T-W732-IX-070',
17:56:40 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
17:56:40 INFO - 'CYGWINBASE': 'C:\\cygwin',
17:56:40 INFO - 'DCLOCATION': 'SCL3',
17:56:40 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
17:56:40 INFO - 'FP_NO_HOST_CHECK': 'NO',
17:56:40 INFO - 'HOMEDRIVE': 'C:',
17:56:40 INFO - 'HOMEPATH': '\\Users\\cltbld',
17:56:40 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
17:56:40 INFO - 'KTS_VERSION': '1.19c',
17:56:40 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
17:56:40 INFO - 'LOGONSERVER': '\\\\T-W732-IX-070',
17:56:40 INFO - 'MONDIR': 'C:\\Monitor_config\\',
17:56:40 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
17:56:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
17:56:40 INFO - 'MOZ_NO_REMOTE': '1',
17:56:40 INFO - 'NO_EM_RESTART': '1',
17:56:40 INFO - 'NUMBER_OF_PROCESSORS': '8',
17:56:40 INFO - 'OS': 'Windows_NT',
17:56:40 INFO - 'OURDRIVE': 'C:',
17:56:40 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
17:56:40 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
17:56:40 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
17:56:40 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
17:56:40 INFO - 'PROCESSOR_LEVEL': '6',
17:56:40 INFO - 'PROCESSOR_REVISION': '1e05',
17:56:40 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
17:56:40 INFO - 'PROGRAMFILES': 'C:\\Program Files',
17:56:40 INFO - 'PROMPT': '$P$G',
17:56:40 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
17:56:40 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
17:56:40 INFO - 'PUBLIC': 'C:\\Users\\Public',
17:56:40 INFO - 'PWD': 'C:\\slave\\test',
17:56:40 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
17:56:40 INFO - 'SLAVEDIR': 'C:\\slave\\',
17:56:40 INFO - 'SYSTEMDRIVE': 'C:',
17:56:40 INFO - 'SYSTEMROOT': 'C:\\windows',
17:56:40 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
17:56:40 INFO - 'TEST1': 'testie',
17:56:40 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
17:56:40 INFO - 'USERDOMAIN': 'T-W732-IX-070',
17:56:40 INFO - 'USERNAME': 'cltbld',
17:56:40 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
17:56:40 INFO - 'WINDIR': 'C:\\windows',
17:56:40 INFO - 'WINDOWS_TRACING_FLAGS': '3',
17:56:40 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
17:56:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
17:56:40 INFO - Ignoring indexes: https://pypi.python.org/simple/
17:56:40 INFO - Downloading/unpacking jsonschema==2.5.1
17:56:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
17:56:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
17:56:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl
17:56:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1)
17:56:41 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
17:56:41 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
17:56:41 INFO - Downloading functools32-3.2.3-2.tar.gz
17:56:41 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32
17:56:42 INFO - warning: no files found matching '*.txt'
17:56:42 INFO - no previously-included directories found matching 'build'
17:56:42 INFO - no previously-included directories found matching 'dist'
17:56:42 INFO - no previously-included directories found matching '.git*'
17:56:42 INFO - Installing collected packages: jsonschema, functools32
17:56:42 INFO - Running setup.py install for functools32
17:56:42 INFO - warning: no files found matching '*.txt'
17:56:42 INFO - no previously-included directories found matching 'build'
17:56:42 INFO - no previously-included directories found matching 'dist'
17:56:42 INFO - no previously-included directories found matching '.git*'
17:56:42 INFO - Successfully installed jsonschema functools32
17:56:42 INFO - Cleaning up...
17:56:43 INFO - Return code: 0
17:56:43 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv
17:56:43 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')]}
17:56:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
17:56:43 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')]}
17:56:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
17:56:43 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 0x019BB640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01A36E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01932B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01A10E90>, '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 0x0130A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F25520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-070', 'USERDOMAIN': 'T-W732-IX-070', '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-070', '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
17:56:43 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
17:56:43 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
17:56:43 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
17:56:43 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
17:56:43 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
17:56:43 INFO - 'COMPUTERNAME': 'T-W732-IX-070',
17:56:43 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
17:56:43 INFO - 'CYGWINBASE': 'C:\\cygwin',
17:56:43 INFO - 'DCLOCATION': 'SCL3',
17:56:43 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
17:56:43 INFO - 'FP_NO_HOST_CHECK': 'NO',
17:56:43 INFO - 'HOMEDRIVE': 'C:',
17:56:43 INFO - 'HOMEPATH': '\\Users\\cltbld',
17:56:43 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
17:56:43 INFO - 'KTS_VERSION': '1.19c',
17:56:43 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
17:56:43 INFO - 'LOGONSERVER': '\\\\T-W732-IX-070',
17:56:43 INFO - 'MONDIR': 'C:\\Monitor_config\\',
17:56:43 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
17:56:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
17:56:43 INFO - 'MOZ_NO_REMOTE': '1',
17:56:43 INFO - 'NO_EM_RESTART': '1',
17:56:43 INFO - 'NUMBER_OF_PROCESSORS': '8',
17:56:43 INFO - 'OS': 'Windows_NT',
17:56:43 INFO - 'OURDRIVE': 'C:',
17:56:43 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;',
17:56:43 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
17:56:43 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
17:56:43 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
17:56:43 INFO - 'PROCESSOR_LEVEL': '6',
17:56:43 INFO - 'PROCESSOR_REVISION': '1e05',
17:56:43 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
17:56:43 INFO - 'PROGRAMFILES': 'C:\\Program Files',
17:56:43 INFO - 'PROMPT': '$P$G',
17:56:43 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
17:56:43 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
17:56:43 INFO - 'PUBLIC': 'C:\\Users\\Public',
17:56:43 INFO - 'PWD': 'C:\\slave\\test',
17:56:43 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
17:56:43 INFO - 'SLAVEDIR': 'C:\\slave\\',
17:56:43 INFO - 'SYSTEMDRIVE': 'C:',
17:56:43 INFO - 'SYSTEMROOT': 'C:\\windows',
17:56:43 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
17:56:43 INFO - 'TEST1': 'testie',
17:56:43 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
17:56:43 INFO - 'USERDOMAIN': 'T-W732-IX-070',
17:56:43 INFO - 'USERNAME': 'cltbld',
17:56:43 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
17:56:43 INFO - 'WINDIR': 'C:\\windows',
17:56:43 INFO - 'WINDOWS_TRACING_FLAGS': '3',
17:56:43 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
17:56:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
17:56:43 INFO - Ignoring indexes: https://pypi.python.org/simple/
17:56:43 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages
17:56:43 INFO - Cleaning up...
17:56:43 INFO - Return code: 0
17:56:43 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv
17:56:43 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')]}
17:56:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
17:56:43 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')]}
17:56:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
17:56:43 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 0x019BB640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01A36E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01932B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01A10E90>, '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 0x0130A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F25520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-070', 'USERDOMAIN': 'T-W732-IX-070', '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-070', '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
17:56:43 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
17:56:43 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
17:56:43 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
17:56:43 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
17:56:43 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
17:56:43 INFO - 'COMPUTERNAME': 'T-W732-IX-070',
17:56:43 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
17:56:43 INFO - 'CYGWINBASE': 'C:\\cygwin',
17:56:43 INFO - 'DCLOCATION': 'SCL3',
17:56:43 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
17:56:43 INFO - 'FP_NO_HOST_CHECK': 'NO',
17:56:43 INFO - 'HOMEDRIVE': 'C:',
17:56:43 INFO - 'HOMEPATH': '\\Users\\cltbld',
17:56:43 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
17:56:43 INFO - 'KTS_VERSION': '1.19c',
17:56:43 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
17:56:43 INFO - 'LOGONSERVER': '\\\\T-W732-IX-070',
17:56:43 INFO - 'MONDIR': 'C:\\Monitor_config\\',
17:56:43 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
17:56:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
17:56:43 INFO - 'MOZ_NO_REMOTE': '1',
17:56:43 INFO - 'NO_EM_RESTART': '1',
17:56:43 INFO - 'NUMBER_OF_PROCESSORS': '8',
17:56:43 INFO - 'OS': 'Windows_NT',
17:56:43 INFO - 'OURDRIVE': 'C:',
17:56:43 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;',
17:56:43 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
17:56:43 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
17:56:43 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
17:56:43 INFO - 'PROCESSOR_LEVEL': '6',
17:56:43 INFO - 'PROCESSOR_REVISION': '1e05',
17:56:43 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
17:56:43 INFO - 'PROGRAMFILES': 'C:\\Program Files',
17:56:43 INFO - 'PROMPT': '$P$G',
17:56:43 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
17:56:43 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
17:56:43 INFO - 'PUBLIC': 'C:\\Users\\Public',
17:56:43 INFO - 'PWD': 'C:\\slave\\test',
17:56:43 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
17:56:43 INFO - 'SLAVEDIR': 'C:\\slave\\',
17:56:43 INFO - 'SYSTEMDRIVE': 'C:',
17:56:43 INFO - 'SYSTEMROOT': 'C:\\windows',
17:56:43 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
17:56:43 INFO - 'TEST1': 'testie',
17:56:43 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
17:56:43 INFO - 'USERDOMAIN': 'T-W732-IX-070',
17:56:43 INFO - 'USERNAME': 'cltbld',
17:56:43 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
17:56:43 INFO - 'WINDIR': 'C:\\windows',
17:56:43 INFO - 'WINDOWS_TRACING_FLAGS': '3',
17:56:43 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
17:56:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
17:56:43 INFO - Ignoring indexes: https://pypi.python.org/simple/
17:56:43 INFO - Downloading/unpacking blobuploader==1.2.4
17:56:43 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
17:56:43 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
17:56:44 INFO - Downloading blobuploader-1.2.4.tar.gz
17:56:44 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader
17:56:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4)
17:56:44 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
17:56:44 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
17:56:45 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests
17:56:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4)
17:56:45 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
17:56:45 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
17:56:45 INFO - Downloading docopt-0.6.1.tar.gz
17:56:45 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt
17:56:45 INFO - Installing collected packages: blobuploader, requests, docopt
17:56:45 INFO - Running setup.py install for blobuploader
17:56:45 INFO - Running setup.py install for requests
17:56:46 INFO - Running setup.py install for docopt
17:56:47 INFO - Successfully installed blobuploader requests docopt
17:56:47 INFO - Cleaning up...
17:56:48 INFO - Return code: 0
17:56:48 INFO - Installing None into virtualenv C:\slave\test\build/venv
17:56:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
17:56:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
17:56:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
17:56:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
17:56:48 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 0x019BB640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01A36E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01932B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01A10E90>, '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 0x0130A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F25520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-070', 'USERDOMAIN': 'T-W732-IX-070', '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-070', '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
17:56:48 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
17:56:48 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
17:56:48 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
17:56:48 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
17:56:48 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
17:56:48 INFO - 'COMPUTERNAME': 'T-W732-IX-070',
17:56:48 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
17:56:48 INFO - 'CYGWINBASE': 'C:\\cygwin',
17:56:48 INFO - 'DCLOCATION': 'SCL3',
17:56:48 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
17:56:48 INFO - 'FP_NO_HOST_CHECK': 'NO',
17:56:48 INFO - 'HOMEDRIVE': 'C:',
17:56:48 INFO - 'HOMEPATH': '\\Users\\cltbld',
17:56:48 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
17:56:48 INFO - 'KTS_VERSION': '1.19c',
17:56:48 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
17:56:48 INFO - 'LOGONSERVER': '\\\\T-W732-IX-070',
17:56:48 INFO - 'MONDIR': 'C:\\Monitor_config\\',
17:56:48 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
17:56:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
17:56:48 INFO - 'MOZ_NO_REMOTE': '1',
17:56:48 INFO - 'NO_EM_RESTART': '1',
17:56:48 INFO - 'NUMBER_OF_PROCESSORS': '8',
17:56:48 INFO - 'OS': 'Windows_NT',
17:56:48 INFO - 'OURDRIVE': 'C:',
17:56:48 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
17:56:48 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
17:56:48 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
17:56:48 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
17:56:48 INFO - 'PROCESSOR_LEVEL': '6',
17:56:48 INFO - 'PROCESSOR_REVISION': '1e05',
17:56:48 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
17:56:48 INFO - 'PROGRAMFILES': 'C:\\Program Files',
17:56:48 INFO - 'PROMPT': '$P$G',
17:56:48 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
17:56:48 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
17:56:48 INFO - 'PUBLIC': 'C:\\Users\\Public',
17:56:48 INFO - 'PWD': 'C:\\slave\\test',
17:56:48 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
17:56:48 INFO - 'SLAVEDIR': 'C:\\slave\\',
17:56:48 INFO - 'SYSTEMDRIVE': 'C:',
17:56:48 INFO - 'SYSTEMROOT': 'C:\\windows',
17:56:48 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
17:56:48 INFO - 'TEST1': 'testie',
17:56:48 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
17:56:48 INFO - 'USERDOMAIN': 'T-W732-IX-070',
17:56:48 INFO - 'USERNAME': 'cltbld',
17:56:48 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
17:56:48 INFO - 'WINDIR': 'C:\\windows',
17:56:48 INFO - 'WINDOWS_TRACING_FLAGS': '3',
17:56:48 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
17:56:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
17:56:48 INFO - Ignoring indexes: https://pypi.python.org/simple/
17:56:48 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
17:56:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-iylyp5-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
17:56:48 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
17:56:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-j5zq10-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
17:56:48 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
17:56:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-if9lu4-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
17:56:48 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
17:56:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bcs0s5-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
17:56:49 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
17:56:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-97fy0x-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
17:56:49 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
17:56:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fskkpi-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
17:56:49 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
17:56:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-0tgexo-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
17:56:49 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
17:56:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xw6x8g-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
17:56:49 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
17:56:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-3i3x0n-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
17:56:49 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
17:56:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-w9tccp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
17:56:49 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
17:56:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-39jjhp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
17:56:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
17:56:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jcgdro-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
17:56:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
17:56:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fgjqor-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
17:56:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
17:56:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-5rptym-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
17:56:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
17:56:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-oay175-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
17:56:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
17:56:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-judjkl-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
17:56:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
17:56:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-tkzggb-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
17:56:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
17:56:51 INFO - Running setup.py install for manifestparser
17:56:51 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts
17:56:51 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts
17:56:51 INFO - Running setup.py install for mozcrash
17:56:52 INFO - Running setup.py install for mozdebug
17:56:52 INFO - Running setup.py install for mozdevice
17:56:52 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts
17:56:52 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts
17:56:52 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts
17:56:52 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts
17:56:52 INFO - Running setup.py install for mozfile
17:56:53 INFO - Running setup.py install for mozhttpd
17:56:53 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts
17:56:53 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts
17:56:53 INFO - Running setup.py install for mozinfo
17:56:53 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts
17:56:53 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts
17:56:54 INFO - Running setup.py install for mozInstall
17:56:54 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts
17:56:54 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts
17:56:54 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts
17:56:54 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts
17:56:54 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts
17:56:54 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts
17:56:54 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts
17:56:54 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts
17:56:54 INFO - Running setup.py install for mozleak
17:56:54 INFO - Running setup.py install for mozlog
17:56:55 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts
17:56:55 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts
17:56:55 INFO - Running setup.py install for moznetwork
17:56:55 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts
17:56:55 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts
17:56:56 INFO - Running setup.py install for mozprocess
17:56:56 INFO - Running setup.py install for mozprofile
17:56:56 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts
17:56:56 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts
17:56:56 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts
17:56:56 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts
17:56:56 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts
17:56:56 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts
17:56:57 INFO - Running setup.py install for mozrunner
17:56:57 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts
17:56:57 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts
17:56:57 INFO - Running setup.py install for mozscreenshot
17:56:58 INFO - Running setup.py install for moztest
17:56:58 INFO - Running setup.py install for mozversion
17:56:58 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts
17:56:58 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts
17:56:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion
17:56:59 INFO - Cleaning up...
17:56:59 INFO - Return code: 0
17:56:59 INFO - Installing None into virtualenv C:\slave\test\build/venv
17:56:59 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')]}
17:56:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
17:56:59 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')]}
17:56:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
17:56:59 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 0x019BB640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01A36E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01932B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01A10E90>, '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 0x0130A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F25520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-070', 'USERDOMAIN': 'T-W732-IX-070', '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-070', '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
17:56:59 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
17:56:59 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
17:56:59 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
17:56:59 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
17:56:59 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
17:56:59 INFO - 'COMPUTERNAME': 'T-W732-IX-070',
17:56:59 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
17:56:59 INFO - 'CYGWINBASE': 'C:\\cygwin',
17:56:59 INFO - 'DCLOCATION': 'SCL3',
17:56:59 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
17:56:59 INFO - 'FP_NO_HOST_CHECK': 'NO',
17:56:59 INFO - 'HOMEDRIVE': 'C:',
17:56:59 INFO - 'HOMEPATH': '\\Users\\cltbld',
17:56:59 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
17:56:59 INFO - 'KTS_VERSION': '1.19c',
17:56:59 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
17:56:59 INFO - 'LOGONSERVER': '\\\\T-W732-IX-070',
17:56:59 INFO - 'MONDIR': 'C:\\Monitor_config\\',
17:56:59 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
17:56:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
17:56:59 INFO - 'MOZ_NO_REMOTE': '1',
17:56:59 INFO - 'NO_EM_RESTART': '1',
17:56:59 INFO - 'NUMBER_OF_PROCESSORS': '8',
17:56:59 INFO - 'OS': 'Windows_NT',
17:56:59 INFO - 'OURDRIVE': 'C:',
17:56:59 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;',
17:56:59 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
17:56:59 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
17:56:59 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
17:56:59 INFO - 'PROCESSOR_LEVEL': '6',
17:56:59 INFO - 'PROCESSOR_REVISION': '1e05',
17:56:59 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
17:56:59 INFO - 'PROGRAMFILES': 'C:\\Program Files',
17:56:59 INFO - 'PROMPT': '$P$G',
17:56:59 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
17:56:59 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
17:56:59 INFO - 'PUBLIC': 'C:\\Users\\Public',
17:56:59 INFO - 'PWD': 'C:\\slave\\test',
17:56:59 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
17:56:59 INFO - 'SLAVEDIR': 'C:\\slave\\',
17:56:59 INFO - 'SYSTEMDRIVE': 'C:',
17:56:59 INFO - 'SYSTEMROOT': 'C:\\windows',
17:56:59 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
17:56:59 INFO - 'TEST1': 'testie',
17:56:59 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
17:56:59 INFO - 'USERDOMAIN': 'T-W732-IX-070',
17:56:59 INFO - 'USERNAME': 'cltbld',
17:56:59 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
17:56:59 INFO - 'WINDIR': 'C:\\windows',
17:56:59 INFO - 'WINDOWS_TRACING_FLAGS': '3',
17:56:59 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
17:56:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
17:56:59 INFO - Ignoring indexes: https://pypi.python.org/simple/
17:56:59 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
17:56:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qcrg_r-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
17:56:59 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))
17:56:59 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
17:56:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qhnxg5-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
17:56:59 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))
17:56:59 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
17:56:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-5enpia-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
17:56:59 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))
17:56:59 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
17:57:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qntxpq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
17:57:00 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))
17:57:00 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
17:57:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-2rz7fx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
17:57:00 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))
17:57:00 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
17:57:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gmwrhw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
17:57:00 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))
17:57:00 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
17:57:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vllgfs-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
17:57:00 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))
17:57:00 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
17:57:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-e26udx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
17:57:00 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))
17:57:00 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
17:57:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ayesoi-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
17:57:00 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))
17:57:00 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
17:57:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-yzvf88-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
17:57:01 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))
17:57:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
17:57:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cstm4y-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
17:57:01 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))
17:57:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
17:57:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-nwhrqm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
17:57:01 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))
17:57:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
17:57:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-irvfsg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
17:57:01 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))
17:57:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
17:57:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-oshk47-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
17:57:01 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))
17:57:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
17:57:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-8cnvym-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
17:57:01 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))
17:57:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
17:57:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-racmho-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
17:57:02 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))
17:57:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
17:57:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-4iliee-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
17:57:02 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))
17:57:02 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))
17:57:02 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))
17:57:02 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))
17:57:02 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))
17:57:02 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))
17:57:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10))
17:57:02 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
17:57:02 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
17:57:03 INFO - Downloading blessings-1.6.tar.gz
17:57:03 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings
17:57:03 INFO - Installing collected packages: blessings
17:57:03 INFO - Running setup.py install for blessings
17:57:03 INFO - Successfully installed blessings
17:57:03 INFO - Cleaning up...
17:57:06 INFO - Return code: 0
17:57:06 INFO - Done creating virtualenv C:\slave\test\build/venv.
17:57:06 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
17:57:06 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
17:57:06 INFO - Reading from file tmpfile_stdout
17:57:06 INFO - Using _rmtree_windows ...
17:57:06 INFO - Using _rmtree_windows ...
17:57:06 INFO - Current package versions:
17:57:06 INFO - blessings == 1.6
17:57:06 INFO - blobuploader == 1.2.4
17:57:06 INFO - docopt == 0.6.1
17:57:06 INFO - functools32 == 3.2.3-2
17:57:06 INFO - jsonschema == 2.5.1
17:57:06 INFO - manifestparser == 1.1
17:57:06 INFO - mozInstall == 1.12
17:57:06 INFO - mozcrash == 0.17
17:57:06 INFO - mozdebug == 0.1
17:57:06 INFO - mozdevice == 0.48
17:57:06 INFO - mozfile == 1.2
17:57:06 INFO - mozhttpd == 0.7
17:57:06 INFO - mozinfo == 0.9
17:57:06 INFO - mozleak == 0.1
17:57:06 INFO - mozlog == 3.4
17:57:06 INFO - moznetwork == 0.27
17:57:06 INFO - mozprocess == 0.23
17:57:06 INFO - mozprofile == 0.28
17:57:06 INFO - mozrunner == 6.12
17:57:06 INFO - mozscreenshot == 0.1
17:57:06 INFO - mozsystemmonitor == 0.3
17:57:06 INFO - moztest == 0.7
17:57:06 INFO - mozversion == 1.4
17:57:06 INFO - psutil == 3.1.1
17:57:06 INFO - requests == 1.2.3
17:57:06 INFO - Installing None into virtualenv C:\slave\test\build/venv
17:57:06 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')]}
17:57:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
17:57:06 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')]}
17:57:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
17:57:06 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 0x019BB640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01A36E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01932B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01A10E90>, '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 0x0130A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F25520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-070', 'USERDOMAIN': 'T-W732-IX-070', '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-070', '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
17:57:07 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
17:57:07 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
17:57:07 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
17:57:07 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
17:57:07 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
17:57:07 INFO - 'COMPUTERNAME': 'T-W732-IX-070',
17:57:07 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
17:57:07 INFO - 'CYGWINBASE': 'C:\\cygwin',
17:57:07 INFO - 'DCLOCATION': 'SCL3',
17:57:07 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
17:57:07 INFO - 'FP_NO_HOST_CHECK': 'NO',
17:57:07 INFO - 'HOMEDRIVE': 'C:',
17:57:07 INFO - 'HOMEPATH': '\\Users\\cltbld',
17:57:07 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
17:57:07 INFO - 'KTS_VERSION': '1.19c',
17:57:07 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
17:57:07 INFO - 'LOGONSERVER': '\\\\T-W732-IX-070',
17:57:07 INFO - 'MONDIR': 'C:\\Monitor_config\\',
17:57:07 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
17:57:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
17:57:07 INFO - 'MOZ_NO_REMOTE': '1',
17:57:07 INFO - 'NO_EM_RESTART': '1',
17:57:07 INFO - 'NUMBER_OF_PROCESSORS': '8',
17:57:07 INFO - 'OS': 'Windows_NT',
17:57:07 INFO - 'OURDRIVE': 'C:',
17:57:07 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;',
17:57:07 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
17:57:07 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
17:57:07 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
17:57:07 INFO - 'PROCESSOR_LEVEL': '6',
17:57:07 INFO - 'PROCESSOR_REVISION': '1e05',
17:57:07 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
17:57:07 INFO - 'PROGRAMFILES': 'C:\\Program Files',
17:57:07 INFO - 'PROMPT': '$P$G',
17:57:07 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
17:57:07 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
17:57:07 INFO - 'PUBLIC': 'C:\\Users\\Public',
17:57:07 INFO - 'PWD': 'C:\\slave\\test',
17:57:07 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
17:57:07 INFO - 'SLAVEDIR': 'C:\\slave\\',
17:57:07 INFO - 'SYSTEMDRIVE': 'C:',
17:57:07 INFO - 'SYSTEMROOT': 'C:\\windows',
17:57:07 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
17:57:07 INFO - 'TEST1': 'testie',
17:57:07 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
17:57:07 INFO - 'USERDOMAIN': 'T-W732-IX-070',
17:57:07 INFO - 'USERNAME': 'cltbld',
17:57:07 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
17:57:07 INFO - 'WINDIR': 'C:\\windows',
17:57:07 INFO - 'WINDOWS_TRACING_FLAGS': '3',
17:57:07 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
17:57:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
17:57:07 INFO - Ignoring indexes: https://pypi.python.org/simple/
17:57:07 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))
17:57:07 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))
17:57:07 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))
17:57:07 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))
17:57:07 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))
17:57:07 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))
17:57:07 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))
17:57:07 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))
17:57:07 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))
17:57:07 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))
17:57:07 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))
17:57:07 INFO - Cleaning up...
17:57:07 INFO - Return code: 0
17:57:07 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv
17:57:07 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')]}
17:57:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
17:57:07 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')]}
17:57:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
17:57:07 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 0x019BB640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01A36E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01932B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01A10E90>, '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 0x0130A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F25520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-070', 'USERDOMAIN': 'T-W732-IX-070', '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-070', '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
17:57:07 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
17:57:07 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
17:57:07 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
17:57:07 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
17:57:07 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
17:57:07 INFO - 'COMPUTERNAME': 'T-W732-IX-070',
17:57:07 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
17:57:07 INFO - 'CYGWINBASE': 'C:\\cygwin',
17:57:07 INFO - 'DCLOCATION': 'SCL3',
17:57:07 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
17:57:07 INFO - 'FP_NO_HOST_CHECK': 'NO',
17:57:07 INFO - 'HOMEDRIVE': 'C:',
17:57:07 INFO - 'HOMEPATH': '\\Users\\cltbld',
17:57:07 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
17:57:07 INFO - 'KTS_VERSION': '1.19c',
17:57:07 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
17:57:07 INFO - 'LOGONSERVER': '\\\\T-W732-IX-070',
17:57:07 INFO - 'MONDIR': 'C:\\Monitor_config\\',
17:57:07 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
17:57:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
17:57:07 INFO - 'MOZ_NO_REMOTE': '1',
17:57:07 INFO - 'NO_EM_RESTART': '1',
17:57:07 INFO - 'NUMBER_OF_PROCESSORS': '8',
17:57:07 INFO - 'OS': 'Windows_NT',
17:57:07 INFO - 'OURDRIVE': 'C:',
17:57:07 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;',
17:57:07 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
17:57:07 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
17:57:07 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
17:57:07 INFO - 'PROCESSOR_LEVEL': '6',
17:57:07 INFO - 'PROCESSOR_REVISION': '1e05',
17:57:07 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
17:57:07 INFO - 'PROGRAMFILES': 'C:\\Program Files',
17:57:07 INFO - 'PROMPT': '$P$G',
17:57:07 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
17:57:07 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
17:57:07 INFO - 'PUBLIC': 'C:\\Users\\Public',
17:57:07 INFO - 'PWD': 'C:\\slave\\test',
17:57:07 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
17:57:07 INFO - 'SLAVEDIR': 'C:\\slave\\',
17:57:07 INFO - 'SYSTEMDRIVE': 'C:',
17:57:07 INFO - 'SYSTEMROOT': 'C:\\windows',
17:57:07 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
17:57:07 INFO - 'TEST1': 'testie',
17:57:07 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
17:57:07 INFO - 'USERDOMAIN': 'T-W732-IX-070',
17:57:07 INFO - 'USERNAME': 'cltbld',
17:57:07 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
17:57:07 INFO - 'WINDIR': 'C:\\windows',
17:57:07 INFO - 'WINDOWS_TRACING_FLAGS': '3',
17:57:07 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
17:57:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
17:57:07 INFO - Ignoring indexes: https://pypi.python.org/simple/
17:57:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages
17:57:07 INFO - Cleaning up...
17:57:07 INFO - Return code: 0
17:57:07 INFO - Running post-action listener: _resource_record_post_action
17:57:07 INFO - Running post-action listener: _start_resource_monitoring
17:57:07 INFO - Starting resource monitoring.
17:57:07 INFO - [mozharness: 2016-10-22 00:57:07.849000Z] Finished create-virtualenv step (success)
17:57:07 INFO - [mozharness: 2016-10-22 00:57:07.849000Z] Running install step.
17:57:07 INFO - Running pre-action listener: _resource_record_pre_action
17:57:07 INFO - Running main action method: install
17:57:07 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
17:57:07 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
17:57:08 INFO - Reading from file tmpfile_stdout
17:57:08 INFO - Using _rmtree_windows ...
17:57:08 INFO - Using _rmtree_windows ...
17:57:08 INFO - Detecting whether we're running mozinstall >=1.0...
17:57:08 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h']
17:57:08 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h
17:57:08 INFO - Reading from file tmpfile_stdout
17:57:08 INFO - Output received:
17:57:08 INFO - Usage: mozinstall-script.py [options] installer
17:57:08 INFO - Options:
17:57:08 INFO - -h, --help show this help message and exit
17:57:08 INFO - -d DEST, --destination=DEST
17:57:08 INFO - Directory to install application into. [default:
17:57:08 INFO - "C:\slave\test"]
17:57:08 INFO - --app=APP Application being installed. [default: firefox]
17:57:08 INFO - Using _rmtree_windows ...
17:57:08 INFO - Using _rmtree_windows ...
17:57:08 INFO - mkdir: C:\slave\test\build\application
17:57:08 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']
17:57:08 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
17:57:10 INFO - Reading from file tmpfile_stdout
17:57:10 INFO - Output received:
17:57:10 INFO - C:\slave\test\build\application\firefox\firefox.exe
17:57:10 INFO - Using _rmtree_windows ...
17:57:10 INFO - Using _rmtree_windows ...
17:57:10 INFO - Running post-action listener: _resource_record_post_action
17:57:10 INFO - [mozharness: 2016-10-22 00:57:10.919000Z] Finished install step (success)
17:57:10 INFO - [mozharness: 2016-10-22 00:57:10.919000Z] Running run-tests step.
17:57:10 INFO - Running pre-action listener: _resource_record_pre_action
17:57:10 INFO - Running main action method: run_tests
17:57:10 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version']
17:57:10 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version
17:57:10 INFO - Python 2.7.3
17:57:10 INFO - Return code: 0
17:57:10 INFO - grabbing minidump binary from tooltool
17:57:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
17:57:10 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 0x01A10E90>, '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 0x0130A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F25520>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1
17:57:10 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
17:57:10 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
17:57:10 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
17:57:11 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'...
17:57:11 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmpz_lsln
17:57:11 INFO - INFO - File integrity verified, renaming tmpz_lsln to win32-minidump_stackwalk.exe
17:57:11 INFO - Return code: 0
17:57:11 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755
17:57:11 INFO - mkdir: C:\slave\test\build\blobber_upload_dir
17:57:11 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir
17:57:11 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe
17:57:11 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir
17:57:11 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/1477089004/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-070', '--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
17:57:11 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/1477089004/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-070 --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
17:57:11 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
17:57:11 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
17:57:11 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
17:57:11 INFO - 'COMPUTERNAME': 'T-W732-IX-070',
17:57:11 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
17:57:11 INFO - 'CYGWINBASE': 'C:\\cygwin',
17:57:11 INFO - 'DCLOCATION': 'SCL3',
17:57:11 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
17:57:11 INFO - 'FP_NO_HOST_CHECK': 'NO',
17:57:11 INFO - 'HOMEDRIVE': 'C:',
17:57:11 INFO - 'HOMEPATH': '\\Users\\cltbld',
17:57:11 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
17:57:11 INFO - 'KTS_VERSION': '1.19c',
17:57:11 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
17:57:11 INFO - 'LOGONSERVER': '\\\\T-W732-IX-070',
17:57:11 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir',
17:57:11 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe',
17:57:11 INFO - 'MONDIR': 'C:\\Monitor_config\\',
17:57:11 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
17:57:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
17:57:11 INFO - 'MOZ_NO_REMOTE': '1',
17:57:11 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir',
17:57:11 INFO - 'NO_EM_RESTART': '1',
17:57:11 INFO - 'NUMBER_OF_PROCESSORS': '8',
17:57:11 INFO - 'OS': 'Windows_NT',
17:57:11 INFO - 'OURDRIVE': 'C:',
17:57: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;',
17:57:11 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
17:57:11 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
17:57:11 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
17:57:12 INFO - 'PROCESSOR_LEVEL': '6',
17:57:12 INFO - 'PROCESSOR_REVISION': '1e05',
17:57:12 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
17:57:12 INFO - 'PROGRAMFILES': 'C:\\Program Files',
17:57:12 INFO - 'PROMPT': '$P$G',
17:57:12 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
17:57:12 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
17:57:12 INFO - 'PUBLIC': 'C:\\Users\\Public',
17:57:12 INFO - 'PWD': 'C:\\slave\\test',
17:57:12 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos',
17:57:12 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
17:57:12 INFO - 'SLAVEDIR': 'C:\\slave\\',
17:57:12 INFO - 'SYSTEMDRIVE': 'C:',
17:57:12 INFO - 'SYSTEMROOT': 'C:\\windows',
17:57:12 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
17:57:12 INFO - 'TEST1': 'testie',
17:57:12 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
17:57:12 INFO - 'USERDOMAIN': 'T-W732-IX-070',
17:57:12 INFO - 'USERNAME': 'cltbld',
17:57:12 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
17:57:12 INFO - 'WINDIR': 'C:\\windows',
17:57:12 INFO - 'WINDOWS_TRACING_FLAGS': '3',
17:57:12 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
17:57:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
17:57:12 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/1477089004/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-070', '--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
17:57:12 INFO - mozversion application_buildid: 20161021153004
17:57:12 INFO - mozversion application_changeset: 5639a9f476d08f300c079117e61697f5026b6367
17:57:12 INFO - mozversion application_display_name: Nightly
17:57:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
17:57:12 INFO - mozversion application_name: Firefox
17:57:12 INFO - mozversion application_remotingname: firefox
17:57:12 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team
17:57:12 INFO - mozversion application_vendor: Mozilla
17:57:12 INFO - mozversion application_version: 52.0a1
17:57:12 INFO - mozversion platform_buildid: 20161021153004
17:57:12 INFO - mozversion platform_changeset: 5639a9f476d08f300c079117e61697f5026b6367
17:57:12 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team
17:57:12 INFO - mozversion platform_version: 52.0a1
17:57:12 INFO - using testdate: 1477097832
17:57:12 INFO - actual date: 1477097832
17:57:12 INFO - starting webserver on 'localhost:49804'
17:57:12 INFO - SUITE-START | Running 2 tests
17:57:12 INFO - TEST-START | dromaeo_css
17:57:12 INFO - operating with platform_type : w7_
17:57:12 INFO - Initialising browser for dromaeo_css test...
17:57:13 INFO - TEST-INFO | started process 1100 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpd7cees\profile http://localhost:49804/getInfo.html)
17:57:21 INFO - PROCESS | 1100 | __metrics Screen width/height:1600/1200
17:57:21 INFO - PROCESS | 1100 | colorDepth:24
17:57:21 INFO - PROCESS | 1100 | Browser inner width/height: 1010/674
17:57:21 INFO - PROCESS | 1100 | __metrics
17:57:22 INFO - PROCESS | 1100 | 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"
17:57:22 INFO - PROCESS | 1100 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
17:57:24 INFO - TEST-INFO | 1100: exit 0
17:57:24 INFO - Browser initialized.
17:57:24 INFO - Running cycle 1/1 for dromaeo_css test...
17:57:24 INFO - TEST-INFO | started process 2932 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpd7cees\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1)
17:57:24 INFO - PROCESS | 2932 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0
17:57:35 INFO - PROCESS | 2932 | RSS: Main: 123191296
17:57:35 INFO - PROCESS | 2932 |
17:58:26 INFO - PROCESS | 2932 | 1477097906591 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]
18:00:38 INFO - PROCESS | 2932 | Cycle 1(1): loaded http://localhost:49804/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49804/tests/dromaeo/cssquery-ext.html)
18:00:40 INFO - PROCESS | 2932 | RSS: Main: 306184192
18:00:40 INFO - PROCESS | 2932 |
18:03:43 INFO - PROCESS | 2932 | Cycle 1(1): loaded http://localhost:49804/tests/dromaeo/cssquery-ext.html (next: http://localhost:49804/tests/dromaeo/cssquery-jquery.html)
18:03:44 INFO - PROCESS | 2932 | RSS: Main: 198082560
18:03:44 INFO - PROCESS | 2932 |
18:06:46 INFO - PROCESS | 2932 | Cycle 1(1): loaded http://localhost:49804/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49804/tests/dromaeo/cssquery-mootools.html)
18:06:46 INFO - PROCESS | 2932 | RSS: Main: 132833280
18:06:46 INFO - PROCESS | 2932 |
18:09:48 INFO - PROCESS | 2932 | Cycle 1(1): loaded http://localhost:49804/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49804/tests/dromaeo/cssquery-prototype.html)
18:09:49 INFO - PROCESS | 2932 | RSS: Main: 139165696
18:09:49 INFO - PROCESS | 2932 |
18:12:52 INFO - PROCESS | 2932 | Cycle 1(1): loaded http://localhost:49804/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49804/tests/dromaeo/cssquery-yui.html)
18:12:52 INFO - PROCESS | 2932 | RSS: Main: 133677056
18:12:52 INFO - PROCESS | 2932 |
18:15:55 INFO - PROCESS | 2932 | Cycle 1(1): loaded http://localhost:49804/tests/dromaeo/cssquery-yui.html (next: http://localhost:49804/tests/dromaeo/cssquery-dojo.html)
18:15:56 INFO - PROCESS | 2932 | RSS: Main: 344854528
18:15:56 INFO - PROCESS | 2932 |
18:15:56 INFO - PROCESS | 2932 | __start_tp_report
18:15:56 INFO - PROCESS | 2932 | _x_x_mozilla_page_load
18:15:56 INFO - PROCESS | 2932 | _x_x_mozilla_page_load_details
18:15:56 INFO - PROCESS | 2932 | |i|pagename|runs|
18:15:56 INFO - PROCESS | 2932 | |0;dojo.html;2690.139155470249;2701.405690748036;2712.3490362313046;2733.7812974962003;2739.2330147558678;4594.425696787902;4599.908001839961;4642.396488456496;4682.157211701897;4706.247000479924;4560.977195114028;4599.747013914233;4605.125026245011;4644.907101857961;4665.556772106653;4128.298189307825;4129.669626429878;4156.97921510392;4157.397177409274;4166.395872598471;4111.8972025699295;4122.15495823357;4127.566605506424;4145.585441455845;4149;4135.917281654365;4141.647959923395;4151.979240103795;4160.604742549453;4168.62482376586;3856.6887258332304;4067.0849058961644;4126.442930204414;4194.979025104855;4195.643370313527;3946.6842652587725;3955.9011024724323;3993.660538854172;4042.191561687674;4073.5722749111214;4542.114287713897;4565.931511027337;4621.445426548802;4637.258038713823;4672.742999135046;4762.333273341735;4795.112904112751;4870.220764677634;4899.779509922062;4900.558949694509;4807.951920480786;4852.757362131879;4863.2461968394955;4919.508049195088;4962.206047032493;4514.7291162530355;4532.546745325474;4542.227821270359;4549.11292298002;4556.134334476439;4895.437024742129;4936.703797772145;4943.90112197754;4957.628177886673;4993.525615066563;4778.474367819537;4787.61699064074;4792.976035119872;4798.0164066366315;4802.351682522884;4796.112719146969;4851.320815085891;4869.975650121798;4871.171900776876;4876.609871210295;4869.756512174377;4874.95125048745;4899.755012249445;4901.240307752304;4907.993861258434;4810.494898035726;4890.633202509826;4903.190973489405;4909.558139767438;4912.508749125059;3055.4194703006356;3156.1478400832107;3258.576385069926;3262.7063564278856;3317.15412569798;2981.701829817001;3033.4691428999836;3037.301420673213;3037.3621539476517;3049.054793014173;4306.870793876189;4523.982104026633;4579.610733087658;4587.6788624795945;4651.883702907387;4645.6980296280635;4896.46138924718;4943.060818444484;4985.376827896513;5011.173156429149;4570.794314255866;4580.1068791585985;4627.67606267558;4669.486356500782;4674.439067311944;3991.860284890015;4047.534533528682;4063.2686116499312;4083.2854250506043;4112.773797441109;4936.827211047596;5061.114304996538;5075.898482030412;5078.619103567247;5084.720340381315;4621.445426548836;4922.7538623067985;4929.580985616263;4936.259561065725;4939.28380384835;4954;4992.226204938241;5007.624428167975;5055.039542486916;5060.240963855304;4529;4744.715317080986;4767.594754445911;4770.379850619397;4786.688865223749;4741.668083234141;4827.517248275144;4849.854504364875;4880.267959806058;4885.145099607484;4631;4662.184117779443;4682.742449165328;4687.320338550823;4765.571098601143;4685.437747470325;4742.193827049479;4767.213409787485;4796.040791841576;4800.903981920412;4529.614982726505;4671.11248862715;4687.9765601171775;4745.359376484131;4757.785899634456;4642.094791515622;4686.250199967989;4807.975960120317;4816.831410900601;4833.05755377698;4491.438570178728;4711;4739.099571081483;4741.478437371955;4745.311929770233
18:15:56 INFO - PROCESS | 2932 | |1;ext.html;515.6802782274989;542.9538489228415;570.7688386203587;584.0596639410545;591.4883625663797;30447;30894;38225.23549529008;39710;40345.394819077665;39059.80470097653;41227.58772412275;41285.38071928916;41521;41567.16865662685;7695.769126926201;7973.083095444011;7978.36173106153;8010.1589333120055;8014.519128852258;6962.408195303406;7114.110736157981;9207.631694732203;9955.651552195675;9957;9985.301028927977;10109.34289271195;10124.392536447836;10147.492625368739;10152.898471015269;9632.988536203668;10047.196224302075;10076.69769906904;10086.041826026516;10096.242781791396;6924.965375173142;7078.575285482888;7103.573785572882;9477.573509192103;9802.9509852451;37701.62298377009;38165.80917095424;38523.22953540942;38532.229355413016;38577.22845543102;2871.7271859173347;2983.8806447742063;3008.27801327682;3374.240795820945;3387.3225354929114;28444.431111377657;29041.41917161666;29059.854700726362;29520.409591808257;30826.383472330428;3184.9681503185134;3297.686719761619;3441.638627944055;3453.6028356739043;3570.428731402989;300994;306008;313224;313882;314635;53215.73392133092;53390.73304633452;53512.19731704028;53752.19371709349;53761.1935820963;55286;55559;55791.44208557944;55865.72067139638;56074.43925560774;13927.164370137823;14327.35526901292;14345.426182952773;14365.6408589784;14374.85625143756;9100.908990910006;10752.623658171926;10865.45672716361;10939.945300273448;10991.285566438157;15192.696146077238;16981.745273820903;17203.569910752332;17357.91321043412;17595.824041759675;6671.166104236971;6725.66371681414;6750.898736518956;6773.830654233683;6779.5932244064525;776.8407476467311;828.5981299069946;920.3051695969634;927.5825878354767;956.8325543029941;27063.729362706516;27114.5932811008;28193.859030704716;28510.572341414896;28519.144425667262;849.9702510412229;875.8554838451712;904.9095090490898;941.4539567051231;996.7906739584769;13403.731925361439;14297.85702142986;14384.640383990274;14520.128792272497;14619.634509137144;489329;552065;581606;588826;619774.9348850935;49274.753626232356;50114.498855010985;50367.49632503701;50621.240681389805;50726;1024.416082832778;1036.3315661398356;1105.2926127278547;1133.438947720867;1158.5713286084035;27660.308492287855;28411;28775.04637526891;28868.27829304284;29000.564991525567;41705.79147104245;42836;43272.783636081615;43405;43472.130557388045;365437;367651;367694;368481;368624;21538;22878;23004.884975575016;23012.19457318986;23666.88166559156;43251.56748432601;43581.85972332623;43611.78194109009;43631;43949;35942.8202858984;36625;37261.25477490489;38536.61463385443;38576.22847542977;35892;36324.455133172494;37126.25747484981;37180.25639487249;37607.811960940024;34462.48306275358;35115.29769404649;35444.645553544135;35466.64533354736;35524.111897202776;32002.67997319997;32944.34111317808;33245.83377083196;34032.65967340394;34115.14712132217;24583.262502124966;25270.74729252684;25295;25379.36551586225;25810.87094564515
18:15:56 INFO - PROCESS | 2932 | |2;jquery.html;280.62256265323134;304.38666087833013;308.92122508760264;310.21515565618984;315.9935605097764;13285.335733213336;17215.655686886254;17395.217215225344;17492.912535437306;17742.822571774293;16271.83728162718;16732.749008764877;16977.83022169778;17233.482995510123;17262.741058884127;5078.31442755227;5234.6335756497065;5241.737913104349;5253.4746525347355;5257.342832145981;4987.526185012436;5070.6957582545165;5141;5176.042432150064;5178.741062946857;4958.355413796201;5115.232715092748;5115.641905066647;5122.897542049157;5125.308083408748;4936.333594964705;4987.401511818605;5085.74571271435;5099.71951542665;5110.361204849394;4357.215701173787;4383.605475507188;4439.667024973108;4446.399736035626;4447.2439685253585;21187.576248474943;22199.223027194133;22219.555608887895;22366.66450003251;22385.664215036788;1840.300685739424;1902.1820617134626;1927.5952050069502;2055.465578949469;2118.6186486432434;14176.787348189786;14206.715865682732;14290.285485725672;14488.406275309708;14703.485378011717;1676;1725.8619310455133;1767.8408943195175;1794.5872449336725;1807.629435965637;83248.58375708082;83413;83561.99999999939;83661.4704676582;84162;8612.526311052952;8860.601272942733;8899.955500222457;9041.095890410907;9455.196308313733;10288.176945844314;10729.83905241422;10772.676819695422;10802.135829133651;11277.898577891146;7040.0847889774;7179.174394944544;7181.497295189287;7243.529170603894;7354.080739907512;7226.349628533457;7331.450141239428;7527.887081693779;7619.961900190575;7636;5610.915836262459;5721.084626459747;5734.111212762028;5738.913916291259;5799.507041901401;1471.2055490035475;1488.8883333750011;1490.2772155504572;1495.9476418325523;1500.3773434024758;732.2713899669756;799.6881216325638;899.2240325357367;899.4693131052579;913.4793167894369;17952.83306585094;18196;18489.260429582668;18544.629107417775;18742.062896855376;794.3208556684067;801.32288216457;811.3549727966281;826.9255766981;855.7432770169049;9455.385399948982;9623.230141588656;10014.79970400588;10119.419548015208;10176.847347289797;371615;422177;426344;447949;450832;95319.52340238255;100175.49912250393;103787;105312.47343763386;109743.45128274307;892.6474042753076;897.5781382750097;923.5936188077359;929.8372784762639;930.2418528898926;13152.671183220496;16012.519624411287;16084.43704470338;17008.234629441456;17102.914485427493;45633.54366456293;45817.77091114523;47965.76017119892;48172.5182748168;51321;62053.68973155286;65478.34521654913;65933.34066659272;66409.66795165994;68477;11487.8276825846;11707.414629268333;12022.458989345676;12148.21036632616;12182.39088045612;13123.212607243597;13546;13625.659358516117;14125.505607303696;14135.858641413453;10697.411642359744;10897.400642964712;10914.454277286262;11064.834027489755;11475.368854712737;10824.512896919816;10957.671269861918;10989.61536346224;11262.887371126184;11667.59163429276;11548.307101573933;11660.06719462458;11704.473298701409;11896.762064758483;12291.078169137172;11904.166708330335;12014.579489717818;12106.939465302617;12137.878621214028;12346.197497162655;7134.536255143399;11484.483198256266;11591.478383472595;11830.46762895689;11932
18:15:56 INFO - PROCESS | 2932 | |3;mootools.html;1196.8743281955392;1214.8906598406143;1256.7235208254183;1504.052446958416;1511.2141686323105;6897.827554311139;7335.4498412619005;7338.559686418818;7338.70645174193;7353.117625884888;6494.772682956098;6725.495587830907;7018.473614478922;7200.35196832285;7200.963995180016;1898.506388339028;1925.8844469331884;1928.556432020636;1929.1897403090666;1929.681602535587;1888.055972013993;1903.1245627011592;1906.2470324221924;1907.523119220195;1922.509760011195;1712.1867113121293;1760.4190617096392;1872.822081902224;1906.2470324221924;1921.2027008791404;1910.0545230111045;1910.1881700277395;1917;1918.7409699690502;1920.5294702797764;1895.981040189608;1914.4830895658233;1931.64264611046;1935.5741736817877;1938.3409640722223;6724.260331363546;6755.189377274758;7152.249013853522;7158.534695244793;7167.569945803269;4061.89845253871;4234.661227101855;4341.240282950471;4343.087951530182;4347.456567929009;2612.8301660392203;2782.4991501529717;2782.7773778097703;2783.248522898826;2820.689724130344;841.6170642357713;851.8126012277289;872.9568166041582;876.8071024374627;882.7175303902753;8589.742307730778;8976.461412315282;8989.505577193251;9159.084091590788;9206.125418085272;3552.2185119273718;3718.8140592970244;3828.464015037897;3828.617138286205;3867.091233560111;7999.3200577950365;8081.434299598972;8083.797905052421;8088.150744171895;8089.959550202211;4255.787210639518;4339.5660433956355;4350.956490435118;4358.063016451478;4362;4041.5756345583877;4052.1490486997473;4068.4710987571425;4071.043304823364;4078.9184216315507;3061.540768884685;3138.8744450222193;3187.505936579834;3214.389266039445;3217.7104060634188;987.9654212102541;988.2736188901152;995.770972676274;1002.9147522460527;1003.9297249192487;713.3544142551;719.7157122936516;721.2823240875363;781.7771934998498;796.9760907172794;4539.750313732777;4627.329037289574;4808.543188397097;4830.565249127595;4863.805447782049;745.6578159313215;754.2721273970535;783.2793829676712;801.5551368990266;803.4014659079023;3175.412548678502;3194.552762613236;3303.2732798784227;3330.7168890644566;3345.080102971702;7909.367250619936;8175.386845986574;8233.711820086388;8694.434861733967;8701.30389568833;8649.437786543855;8653.134686531423;8668.479891206547;8673.783155421164;8674.913250867537;6434.453071488976;6575.210974682973;6613.206415230203;6834.4874134440115;6841.62371069586;4583.083383323282;4702.576768090888;4708.270218116198;4734.834280800155;4775.140474714446;7782.105057918186;7893.960530197312;7906.9209307906185;8052.959735201521;8061.39539534549;7654.387648988179;8039.87940180886;8190.836183276182;8206.343492520704;8220.219079187467;5241.580673546031;5719.170720245458;5744.798932037525;6007.828473447636;6102.938970610237;6699.39705426514;7019.052427922369;7058.294170582901;7058.364747172779;7062.611556364449;5806.259017861686;6392.0411938209645;6407.487401008001;6437.774677886251;6455.903161452676;5994.250718660168;5998.370171131968;6065.514758819372;6084.878302433838;6177.567570270038;5512.614117011771;5516.558675306046;5650.604457687922;5656.971715141398;5799.4780469757925;4003.7197396181987;4019.1358857845703;4094.3039683254515;4146.419501269885;4252.659787216953;3994.7603143811466;4007.4790277264933;4146.5024197096545;4169.145325208386;4233.2168548817335
18:15:56 INFO - PROCESS | 2932 | |4;prototype.html;238.55271366188393;242.6845101368221;253.70063325276178;254.49101796407192;255.05883290657474;5121.924395876861;5143.511366420189;5218.634695571312;5219.9478005219935;5236.355640401592;4898.706077635345;4996.225585034325;5014.774335154918;5089.491050894909;5112.463191364909;3746.1196618794493;3750.268697603968;3751.287255421475;3754.5869954305003;3762.604926482721;3743.270062337845;3744.475773391727;3746.1383881707225;3757.173421847203;3765.5104836371233;3534.876279330224;3630.9273814523694;3631.7639353442073;3698.167912219756;3719.721020923428;3707.703383729295;3710.9814450927843;3720.627937206285;3720.832562534679;3725.938107639337;3106.4097821414084;3140.560321554984;3201.7918835275864;3203.3112880730755;3217.083131307566;4993.600511959071;5010.899782004376;5015.197568389077;5016.799328026875;5039.6220283478515;4246.40550322955;4282.250606143913;4353.9129217415475;4354.194474022284;4374.562543745632;5284.524392804666;5289.576833853283;5290.153575427951;5292.894142117175;5299.788008479656;3517.8241087945757;3570.892873213808;3571.4464258040043;3573.6605022523077;3576.8390422431053;5411.215373770781;5485.451454854522;5537.197106419587;5537.6709589698585;5547.223388725582;3539.261513753063;3649.1606930406156;3651.068977410743;3653.50677658518;3660.9450858237146;3613.584437789688;3657.579378371521;3680.723945704083;3684.447332900033;3691.2986532559266;4275.294576394922;4349.434573505424;4349.978250108729;4356.782160891943;4361.563843615613;5191.584673226208;5306.442823503477;5350.224217488442;5355.3841308249275;5357;1270.4219580091221;1278.1372573512842;1282.7819270723999;1283.1787655900232;1286.3568215892053;810.4691427115249;825.5542007315983;833.7582101190587;841.427829076234;857.6012154348258;5030.798768049311;5036.169032109734;5040.31955685985;5042.697438153649;5045.678987665641;5314.893702125936;5386.8383948481605;5478.945210547843;5487.149491828773;5498.917516237259;5024.799008039638;5026.673266237683;5040.672356296829;5046.192609182586;5066.923996140061;2814.8029637925147;2863.4702580022763;2866.899658511942;2885.870135843892;2932.222960915359;5633.380328163898;5642.23829782974;5651.632643878134;5654.575906807006;5655.886882262414;5396.892062158735;5580.525655319343;5597.160425936061;5604.775808967677;5606.747696353592;5274.206769698321;5655.575831812631;5667.206591077253;5685.488306052475;5781.352314590322;5468.753906074316;5599.664020158803;5602.411746766531;5604.579656525695;5617.803376881953;5526.613137080366;5531.695756733418;5534.446555344433;5546.805861794979;5549.833504994856;5508.586855985737;5516.889662206813;5771.624844385102;5788.9131663024245;5808.73860676279;3529.2941411717247;3553.466979953028;3558;3560.376934036585;3561.8397172126797;5508.228847961209;5577.247071645276;5599.328080630187;5599.972000139973;5638.379778224474;5208.557272631869;5362.892742145057;5448.373437054789;5464.617476776588;5467.58993075513;5313.787448502017;5340.786368545215;5438.265834112344;5457.399686034453;5472.50747432733;5282.97358513205;5300.388661864203;5316.973415132899;5343.198520221998;5343.65266257692;5181.222816577393;5347.171188465639;5427.755750991294;5434.809781657623;5436.700981445901;5070.8225212117395;5230.555402790806;5307.867303317448;5341.78632854697;5369.812056578001
18:15:56 INFO - PROCESS | 2932 | |5;yui.html;611.2390074357426;652.8367908022994;654.7217432591148;657.9835504112393;691.7751730687526;12849.036322275831;13067.477300907964;13203.141795783267;13301.999999999987;13464.326783660803;10973.957474039953;11504.539818407275;11517.942410287958;11525;11588.710282242948;506.2254750232151;616.0881894795693;628.3151365012136;630.6531407725755;633.5976654824196;520.0976306108898;615.5414216408759;620.4416025576971;631.3844002097941;633.4330773957305;663.7875879718492;667.9766208182715;668.2649086005404;668.6623255256113;669.3808227389669;631.9715612797435;663.0948754949508;663.8473151175208;664.6145235763246;665.4942243894626;375.83087610575353;387.79834486067085;475.9881002974919;482.8913494463753;484.4501490807923;12462.75074498505;13127.277999710011;13155.276459794708;13196.20822750638;13269.336533173398;11133.332000080021;11506.769864602746;12019.699507512294;12053.457594408274;12580.182288151333;10594.576216951313;11211.66365009051;11224.494897729623;11241.66275011751;11451.370174640391;3330.550375699274;3357.2949680567112;3421.127612458807;3440.7935523868646;3534.1164708822794;82967.5851620744;83606.58196709039;84713;84919.57540212321;85317.57341213254;9521.381110227812;9779.902200978042;9874.259430542737;9881.936599654131;10179.811340561666;18478.260869565067;18694.439166824744;18783;18813.81186188148;18820.153093110843;6939.583624982517;7053.612051337123;7168.749093781693;7176.7846964591145;7262.2737726228015;3597.1007248187952;3697.020289623252;3711.851525938986;3806.7715937043868;3860.864869729546;3029.33354661974;3200.759943004284;3210.9036728898172;3219.517072439106;3225.8870939517005;547.5646860745717;549.7416214379315;561.3208018297905;561.5956511311931;564.3848205456072;625.9123722678829;626.9811905642838;634.3244444666416;643.231338550437;671.0705672643425;9401;10117.898821011842;10504;10582.894171058191;10619.84070238947;618.9164462797464;667.7529314153697;690.5752961928479;696.2793508718553;702.1784512120831;4593.3339665748945;4818.397700287464;4830.903381932341;4865.391826021747;4874.195757699939;68730;80247;91436;102104.48947755214;120128;80053;91555;98541;105265.47367263115;108171;88298.55850720705;91046.54476727839;92503;93408.53295733478;95017;8878.86681699762;8886.333524985523;9073.682421115229;9123.726288211365;9224.40041397307;46436.767816160704;54293.72853135709;54571;55006;57205.14192287036;71537.92693109711;74268.62865685637;76536;76998.6150069246;80198;11155.219134660496;11497.137714671266;11503.079753619522;11709.824352634887;11711.941440292743;13637.590872273848;13791.793123103362;13822.861771382157;13838.790957026205;13968.650783730489;12520.373981301082;12889;13305.800412994007;13309.134906231064;13334.533291335145;12113.454894529592;12115.939420303195;12478.563250286197;12534.059945504307;12712.237265764084;12353.444095015926;12389.814152787536;12700;12833.037522185687;12954.481820727065;10709.250352475252;11017.44912754375;11064.502097405797;11098.223124381539;11295.71760705956;9994.800103997733;10200.846987295048;10869.619563315562;10968;11171.38557379352
18:15:56 INFO - PROCESS | 2932 | __end_tp_report
18:15:56 INFO - PROCESS | 2932 | __start_cc_report
18:15:56 INFO - PROCESS | 2932 | _x_x_mozilla_cycle_collect,2550
18:15:56 INFO - PROCESS | 2932 | __end_cc_report
18:15:56 INFO - PROCESS | 2932 | __startTimestamp1477098956139__endTimestamp
18:15:56 INFO - PROCESS | 2932 |
18:15:56 INFO - PROCESS | 2932 | ------- Summary: start -------
18:15:56 INFO - PROCESS | 2932 | Number of tests: 6
18:15:56 INFO - PROCESS | 2932 |
18:15:56 INFO - PROCESS | 2932 | [#0] dojo.html Cycles:165 Average:4489.04 Median:4682.16 stddev:563.97 (12.0%) stddev-sans-first:547.76
18:15:56 INFO - PROCESS | 2932 | Values: 2690.1 2701.4 2712.3 2733.8 2739.2 4594.4 4599.9 4642.4 4682.2 4706.2 4561.0 4599.7 4605.1 4644.9 4665.6 4128.3 4129.7 4157.0 4157.4 4166.4 4111.9 4122.2 4127.6 4145.6 4149.0 4135.9 4141.6 4152.0 4160.6 4168.6 3856.7 4067.1 4126.4 4195.0 4195.6 3946.7 3955.9 3993.7 4042.2 4073.6 4542.1 4565.9 4621.4 4637.3 4672.7 4762.3 4795.1 4870.2 4899.8 4900.6 4808.0 4852.8 4863.2 4919.5 4962.2 4514.7 4532.5 4542.2 4549.1 4556.1 4895.4 4936.7 4943.9 4957.6 4993.5 4778.5 4787.6 4793.0 4798.0 4802.4 4796.1 4851.3 4870.0 4871.2 4876.6 4869.8 4875.0 4899.8 4901.2 4908.0 4810.5 4890.6 4903.2 4909.6 4912.5 3055.4 3156.1 3258.6 3262.7 3317.2 2981.7 3033.5 3037.3 3037.4 3049.1 4306.9 4524.0 4579.6 4587.7 4651.9 4645.7 4896.5 4943.1 4985.4 5011.2 4570.8 4580.1 4627.7 4669.5 4674.4 3991.9 4047.5 4063.3 4083.3 4112.8 4936.8 5061.1 5075.9 5078.6 5084.7 4621.4 4922.8 4929.6 4936.3 4939.3 4954.0 4992.2 5007.6 5055.0 5060.2 4529.0 4744.7 4767.6 4770.4 4786.7 4741.7 4827.5 4849.9 4880.3 4885.1 4631.0 4662.2 4682.7 4687.3 4765.6 4685.4 4742.2 4767.2 4796.0 4800.9 4529.6 4671.1 4688.0 4745.4 4757.8 4642.1 4686.3 4808.0 4816.8 4833.1 4491.4 4711.0 4739.1 4741.5 4745.3
18:15:56 INFO - PROCESS | 2932 |
18:15:56 INFO - PROCESS | 2932 | [#1] ext.html Cycles:180 Average:55520.55 Median:368087.50 stddev:114515.08 (31.1%) stddev-sans-first:114761.86
18:15:56 INFO - PROCESS | 2932 | Values: 515.7 543.0 570.8 584.1 591.5 30447.0 30894.0 38225.2 39710.0 40345.4 39059.8 41227.6 41285.4 41521.0 41567.2 7695.8 7973.1 7978.4 8010.2 8014.5 6962.4 7114.1 9207.6 9955.7 9957.0 9985.3 10109.3 10124.4 10147.5 10152.9 9633.0 10047.2 10076.7 10086.0 10096.2 6925.0 7078.6 7103.6 9477.6 9803.0 37701.6 38165.8 38523.2 38532.2 38577.2 2871.7 2983.9 3008.3 3374.2 3387.3 28444.4 29041.4 29059.9 29520.4 30826.4 3185.0 3297.7 3441.6 3453.6 3570.4 300994.0 306008.0 313224.0 313882.0 314635.0 53215.7 53390.7 53512.2 53752.2 53761.2 55286.0 55559.0 55791.4 55865.7 56074.4 13927.2 14327.4 14345.4 14365.6 14374.9 9100.9 10752.6 10865.5 10939.9 10991.3 15192.7 16981.7 17203.6 17357.9 17595.8 6671.2 6725.7 6750.9 6773.8 6779.6 776.8 828.6 920.3 927.6 956.8 27063.7 27114.6 28193.9 28510.6 28519.1 850.0 875.9 904.9 941.5 996.8 13403.7 14297.9 14384.6 14520.1 14619.6 489329.0 552065.0 581606.0 588826.0 619774.9 49274.8 50114.5 50367.5 50621.2 50726.0 1024.4 1036.3 1105.3 1133.4 1158.6 27660.3 28411.0 28775.0 28868.3 29000.6 41705.8 42836.0 43272.8 43405.0 43472.1 365437.0 367651.0 367694.0 368481.0 368624.0 21538.0 22878.0 23004.9 23012.2 23666.9 43251.6 43581.9 43611.8 43631.0 43949.0 35942.8 36625.0 37261.3 38536.6 38576.2 35892.0 36324.5 37126.3 37180.3 37607.8 34462.5 35115.3 35444.6 35466.6 35524.1 32002.7 32944.3 33245.8 34032.7 34115.1 24583.3 25270.7 25295.0 25379.4 25810.9
18:15:56 INFO - PROCESS | 2932 |
18:15:56 INFO - PROCESS | 2932 | [#2] jquery.html Cycles:180 Average:27594.67 Median:11333.14 stddev:71024.48 (626.7%) stddev-sans-first:71194.11
18:15:56 INFO - PROCESS | 2932 | Values: 280.6 304.4 308.9 310.2 316.0 13285.3 17215.7 17395.2 17492.9 17742.8 16271.8 16732.7 16977.8 17233.5 17262.7 5078.3 5234.6 5241.7 5253.5 5257.3 4987.5 5070.7 5141.0 5176.0 5178.7 4958.4 5115.2 5115.6 5122.9 5125.3 4936.3 4987.4 5085.7 5099.7 5110.4 4357.2 4383.6 4439.7 4446.4 4447.2 21187.6 22199.2 22219.6 22366.7 22385.7 1840.3 1902.2 1927.6 2055.5 2118.6 14176.8 14206.7 14290.3 14488.4 14703.5 1676.0 1725.9 1767.8 1794.6 1807.6 83248.6 83413.0 83562.0 83661.5 84162.0 8612.5 8860.6 8900.0 9041.1 9455.2 10288.2 10729.8 10772.7 10802.1 11277.9 7040.1 7179.2 7181.5 7243.5 7354.1 7226.3 7331.5 7527.9 7620.0 7636.0 5610.9 5721.1 5734.1 5738.9 5799.5 1471.2 1488.9 1490.3 1495.9 1500.4 732.3 799.7 899.2 899.5 913.5 17952.8 18196.0 18489.3 18544.6 18742.1 794.3 801.3 811.4 826.9 855.7 9455.4 9623.2 10014.8 10119.4 10176.8 371615.0 422177.0 426344.0 447949.0 450832.0 95319.5 100175.5 103787.0 105312.5 109743.5 892.6 897.6 923.6 929.8 930.2 13152.7 16012.5 16084.4 17008.2 17102.9 45633.5 45817.8 47965.8 48172.5 51321.0 62053.7 65478.3 65933.3 66409.7 68477.0 11487.8 11707.4 12022.5 12148.2 12182.4 13123.2 13546.0 13625.7 14125.5 14135.9 10697.4 10897.4 10914.5 11064.8 11475.4 10824.5 10957.7 10989.6 11262.9 11667.6 11548.3 11660.1 11704.5 11896.8 12291.1 11904.2 12014.6 12106.9 12137.9 12346.2 7134.5 11484.5 11591.5 11830.5 11932.0
18:15:56 INFO - PROCESS | 2932 |
18:15:56 INFO - PROCESS | 2932 | [#3] mootools.html Cycles:180 Average:4614.15 Median:5653.79 stddev:2554.03 (45.2%) stddev-sans-first:2548.28
18:15:56 INFO - PROCESS | 2932 | Values: 1196.9 1214.9 1256.7 1504.1 1511.2 6897.8 7335.4 7338.6 7338.7 7353.1 6494.8 6725.5 7018.5 7200.4 7201.0 1898.5 1925.9 1928.6 1929.2 1929.7 1888.1 1903.1 1906.2 1907.5 1922.5 1712.2 1760.4 1872.8 1906.2 1921.2 1910.1 1910.2 1917.0 1918.7 1920.5 1896.0 1914.5 1931.6 1935.6 1938.3 6724.3 6755.2 7152.2 7158.5 7167.6 4061.9 4234.7 4341.2 4343.1 4347.5 2612.8 2782.5 2782.8 2783.2 2820.7 841.6 851.8 873.0 876.8 882.7 8589.7 8976.5 8989.5 9159.1 9206.1 3552.2 3718.8 3828.5 3828.6 3867.1 7999.3 8081.4 8083.8 8088.2 8090.0 4255.8 4339.6 4351.0 4358.1 4362.0 4041.6 4052.1 4068.5 4071.0 4078.9 3061.5 3138.9 3187.5 3214.4 3217.7 988.0 988.3 995.8 1002.9 1003.9 713.4 719.7 721.3 781.8 797.0 4539.8 4627.3 4808.5 4830.6 4863.8 745.7 754.3 783.3 801.6 803.4 3175.4 3194.6 3303.3 3330.7 3345.1 7909.4 8175.4 8233.7 8694.4 8701.3 8649.4 8653.1 8668.5 8673.8 8674.9 6434.5 6575.2 6613.2 6834.5 6841.6 4583.1 4702.6 4708.3 4734.8 4775.1 7782.1 7894.0 7906.9 8053.0 8061.4 7654.4 8039.9 8190.8 8206.3 8220.2 5241.6 5719.2 5744.8 6007.8 6102.9 6699.4 7019.1 7058.3 7058.4 7062.6 5806.3 6392.0 6407.5 6437.8 6455.9 5994.3 5998.4 6065.5 6084.9 6177.6 5512.6 5516.6 5650.6 5657.0 5799.5 4003.7 4019.1 4094.3 4146.4 4252.7 3994.8 4007.5 4146.5 4169.1 4233.2
18:15:56 INFO - PROCESS | 2932 |
18:15:56 INFO - PROCESS | 2932 | [#4] prototype.html Cycles:180 Average:4420.33 Median:5117.19 stddev:1386.23 (27.1%) stddev-sans-first:1354.12
18:15:56 INFO - PROCESS | 2932 | Values: 238.6 242.7 253.7 254.5 255.1 5121.9 5143.5 5218.6 5219.9 5236.4 4898.7 4996.2 5014.8 5089.5 5112.5 3746.1 3750.3 3751.3 3754.6 3762.6 3743.3 3744.5 3746.1 3757.2 3765.5 3534.9 3630.9 3631.8 3698.2 3719.7 3707.7 3711.0 3720.6 3720.8 3725.9 3106.4 3140.6 3201.8 3203.3 3217.1 4993.6 5010.9 5015.2 5016.8 5039.6 4246.4 4282.3 4353.9 4354.2 4374.6 5284.5 5289.6 5290.2 5292.9 5299.8 3517.8 3570.9 3571.4 3573.7 3576.8 5411.2 5485.5 5537.2 5537.7 5547.2 3539.3 3649.2 3651.1 3653.5 3660.9 3613.6 3657.6 3680.7 3684.4 3691.3 4275.3 4349.4 4350.0 4356.8 4361.6 5191.6 5306.4 5350.2 5355.4 5357.0 1270.4 1278.1 1282.8 1283.2 1286.4 810.5 825.6 833.8 841.4 857.6 5030.8 5036.2 5040.3 5042.7 5045.7 5314.9 5386.8 5478.9 5487.1 5498.9 5024.8 5026.7 5040.7 5046.2 5066.9 2814.8 2863.5 2866.9 2885.9 2932.2 5633.4 5642.2 5651.6 5654.6 5655.9 5396.9 5580.5 5597.2 5604.8 5606.7 5274.2 5655.6 5667.2 5685.5 5781.4 5468.8 5599.7 5602.4 5604.6 5617.8 5526.6 5531.7 5534.4 5546.8 5549.8 5508.6 5516.9 5771.6 5788.9 5808.7 3529.3 3553.5 3558.0 3560.4 3561.8 5508.2 5577.2 5599.3 5600.0 5638.4 5208.6 5362.9 5448.4 5464.6 5467.6 5313.8 5340.8 5438.3 5457.4 5472.5 5283.0 5300.4 5317.0 5343.2 5343.7 5181.2 5347.2 5427.8 5434.8 5436.7 5070.8 5230.6 5307.9 5341.8 5369.8
18:15:56 INFO - PROCESS | 2932 |
18:15:56 INFO - PROCESS | 2932 | [#5] yui.html Cycles:180 Average:19899.12 Median:25515.05 stddev:29351.07 (115.0%) stddev-sans-first:29397.68
18:15:56 INFO - PROCESS | 2932 | Values: 611.2 652.8 654.7 658.0 691.8 12849.0 13067.5 13203.1 13302.0 13464.3 10974.0 11504.5 11517.9 11525.0 11588.7 506.2 616.1 628.3 630.7 633.6 520.1 615.5 620.4 631.4 633.4 663.8 668.0 668.3 668.7 669.4 632.0 663.1 663.8 664.6 665.5 375.8 387.8 476.0 482.9 484.5 12462.8 13127.3 13155.3 13196.2 13269.3 11133.3 11506.8 12019.7 12053.5 12580.2 10594.6 11211.7 11224.5 11241.7 11451.4 3330.6 3357.3 3421.1 3440.8 3534.1 82967.6 83606.6 84713.0 84919.6 85317.6 9521.4 9779.9 9874.3 9881.9 10179.8 18478.3 18694.4 18783.0 18813.8 18820.2 6939.6 7053.6 7168.7 7176.8 7262.3 3597.1 3697.0 3711.9 3806.8 3860.9 3029.3 3200.8 3210.9 3219.5 3225.9 547.6 549.7 561.3 561.6 564.4 625.9 627.0 634.3 643.2 671.1 9401.0 10117.9 10504.0 10582.9 10619.8 618.9 667.8 690.6 696.3 702.2 4593.3 4818.4 4830.9 4865.4 4874.2 68730.0 80247.0 91436.0 102104.5 120128.0 80053.0 91555.0 98541.0 105265.5 108171.0 88298.6 91046.5 92503.0 93408.5 95017.0 8878.9 8886.3 9073.7 9123.7 9224.4 46436.8 54293.7 54571.0 55006.0 57205.1 71537.9 74268.6 76536.0 76998.6 80198.0 11155.2 11497.1 11503.1 11709.8 11711.9 13637.6 13791.8 13822.9 13838.8 13968.7 12520.4 12889.0 13305.8 13309.1 13334.5 12113.5 12115.9 12478.6 12534.1 12712.2 12353.4 12389.8 12700.0 12833.0 12954.5 10709.3 11017.4 11064.5 11098.2 11295.7 9994.8 10200.8 10869.6 10968.0 11171.4
18:15:56 INFO - PROCESS | 2932 | -------- Summary: end --------
18:15:56 INFO - PROCESS | 2932 |
18:15:56 INFO - TEST-INFO | 2932: exit 0
18:15:57 INFO - TEST-OK | dromaeo_css | took 1124587ms
18:15:57 INFO - TEST-START | kraken
18:15:57 INFO - operating with platform_type : w7_
18:15:57 INFO - Initialising browser for kraken test...
18:15:57 INFO - TEST-INFO | started process 3524 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpjv1vit\profile http://localhost:49804/getInfo.html)
18:16:03 INFO - PROCESS | 3524 | __metrics Screen width/height:1600/1200
18:16:03 INFO - PROCESS | 3524 | colorDepth:24
18:16:03 INFO - PROCESS | 3524 | Browser inner width/height: 1010/674
18:16:03 INFO - PROCESS | 3524 | __metrics
18:16:04 INFO - PROCESS | 3524 | 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"
18:16:04 INFO - PROCESS | 3524 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
18:16:04 INFO - PROCESS | 3524 | console.error:
18:16:04 INFO - PROCESS | 3524 | Message: Error: Connection closed before committing the transaction.
18:16:04 INFO - PROCESS | 3524 | Stack:
18:16:04 INFO - PROCESS | 3524 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:937:23
18:16:04 INFO - PROCESS | 3524 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7
18:16:04 INFO - PROCESS | 3524 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11
18:16:04 INFO - PROCESS | 3524 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7
18:16:04 INFO - PROCESS | 3524 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7
18:16:04 INFO - PROCESS | 3524 | TaskImpl_run@resource://gre/modules/Task.jsm:327:15
18:16:04 INFO - PROCESS | 3524 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9
18:16:04 INFO - PROCESS | 3524 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15
18:16:04 INFO - PROCESS | 3524 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5
18:16:04 INFO - PROCESS | 3524 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29
18:16:04 INFO - PROCESS | 3524 | Barrier/this.client.addBlocker/promise13.1%
18:17:02 INFO - TinderboxPrint: I/O read bytes / time
85,974,528 / 1,249,620
18:17:02 INFO - TinderboxPrint: I/O write bytes / time
470,572,544 / 3,674,850
18:17:02 INFO - TinderboxPrint: CPU idle
8,299.8 (86.9%)
18:17:02 INFO - TinderboxPrint: CPU user
1,211.5 (12.7%)
18:17:02 INFO - install - Wall time: 3s; CPU: 12.0%; Read bytes: 0; Write bytes: 36445184; Read time: 0; Write time: 100190
18:17:02 INFO - run-tests - Wall time: 1191s; CPU: 13.0%; Read bytes: 85974528; Write bytes: 397289984; Read time: 1249620; Write time: 3181020
18:17:02 INFO - Running post-run listener: _upload_blobber_files
18:17:02 INFO - Blob upload gear active.
18:17:02 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir.
18:17:02 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
18:17:02 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']
18:17:02 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
18:17:03 INFO - (blobuploader) - INFO - Open directory for files ...
18:17:03 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log ...
18:17:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
18:17:03 INFO - (blobuploader) - INFO - Uploading, attempt #1.
18:17:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded
18:17:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
18:17:04 INFO - (blobuploader) - INFO - Done attempting.
18:17:04 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log ...
18:17:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
18:17:04 INFO - (blobuploader) - INFO - Uploading, attempt #1.
18:17:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded
18:17:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
18:17:05 INFO - (blobuploader) - INFO - Done attempting.
18:17:05 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ...
18:17:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
18:17:05 INFO - (blobuploader) - INFO - Uploading, attempt #1.
18:17:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
18:17:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
18:17:12 INFO - (blobuploader) - INFO - Done attempting.
18:17:12 INFO - (blobuploader) - INFO - Iteration through files over.
18:17:12 INFO - Return code: 0
18:17:12 INFO - rmtree: C:\slave\test\build\uploaded_files.json
18:17:12 INFO - Using _rmtree_windows ...
18:17:12 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1
18:17:12 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ca968b55f929b7ab46b48cc620fa9e089bf3a58084f52189dbe393a45c0916af8ef57bade1216f65390805231480d36eaff4cadeea50f92c012e2c19f5789e7c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6b572ab51e800751280fda968b949a091127a37c31408a785d9045348a0cb47824fa4072dd89296c39deba9a17708b9ba4844eadbcda27e83077ba69096f4549", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/14ca97f31537cdc40dc5f3bb6aad03fc2fc45b0fd0b1561ed1a287a6f5f61a6c0b316474a5ae2f51dde751f67335580086989d359277798969d3e7c60e848ca7"}
18:17:12 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files
18:17:12 INFO - Writing to file C:\slave\test\properties\blobber_files
18:17:12 INFO - Contents:
18:17:12 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ca968b55f929b7ab46b48cc620fa9e089bf3a58084f52189dbe393a45c0916af8ef57bade1216f65390805231480d36eaff4cadeea50f92c012e2c19f5789e7c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6b572ab51e800751280fda968b949a091127a37c31408a785d9045348a0cb47824fa4072dd89296c39deba9a17708b9ba4844eadbcda27e83077ba69096f4549", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/14ca97f31537cdc40dc5f3bb6aad03fc2fc45b0fd0b1561ed1a287a6f5f61a6c0b316474a5ae2f51dde751f67335580086989d359277798969d3e7c60e848ca7"}
18:17:12 INFO - Running post-run listener: copy_logs_to_upload_dir
18:17:12 INFO - Copying logs to upload dir...
18:17:12 INFO - mkdir: C:\slave\test\build\upload\logs
18:17:12 INFO - Copying logs to upload dir...
18:17:12 INFO - Using _rmtree_windows ...
18:17:12 INFO - Using _rmtree_windows ...
18:17:12 INFO - Using _rmtree_windows ...
18:17:12 INFO - Using _rmtree_windows ...
18:17:12 INFO - Using _rmtree_windows ...
18:17:12 INFO - Using _rmtree_windows ...
18:17:12 INFO - Using _rmtree_windows ...
program finished with exit code 0
elapsedTime=1378.632000
========= master_lag: 1.69 =========
========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 0 secs) (at 2016-10-21 18:17:14.714934) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-21 18:17:14.721269) =========
'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-070
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-070
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-070
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/ca968b55f929b7ab46b48cc620fa9e089bf3a58084f52189dbe393a45c0916af8ef57bade1216f65390805231480d36eaff4cadeea50f92c012e2c19f5789e7c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6b572ab51e800751280fda968b949a091127a37c31408a785d9045348a0cb47824fa4072dd89296c39deba9a17708b9ba4844eadbcda27e83077ba69096f4549", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/14ca97f31537cdc40dc5f3bb6aad03fc2fc45b0fd0b1561ed1a287a6f5f61a6c0b316474a5ae2f51dde751f67335580086989d359277798969d3e7c60e848ca7"}
build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477089004/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/1477089004/firefox-52.0a1.en-US.win32.zip'
blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ca968b55f929b7ab46b48cc620fa9e089bf3a58084f52189dbe393a45c0916af8ef57bade1216f65390805231480d36eaff4cadeea50f92c012e2c19f5789e7c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6b572ab51e800751280fda968b949a091127a37c31408a785d9045348a0cb47824fa4072dd89296c39deba9a17708b9ba4844eadbcda27e83077ba69096f4549", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/14ca97f31537cdc40dc5f3bb6aad03fc2fc45b0fd0b1561ed1a287a6f5f61a6c0b316474a5ae2f51dde751f67335580086989d359277798969d3e7c60e848ca7"}'
========= master_lag: 0.01 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-21 18:17:14.838105) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-21 18:17:14.838433) =========
'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-070
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-070
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-070
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-21 18:17:14.949829) =========
========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-10-21 18:17:14.950216) =========
========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-10-21 18:17:15.459435) =========
========= Total master_lag: 4.24 =========