builder: fx-team_win7_ix_test-dromaeojs-pgo
slave: t-w732-ix-114
starttime: 1477767820.58
results: success (0)
buildid: 20161029120230
builduid: a93d09fecd474b86919849bb52f9435b
revision: 969c3295d3aa77931cca26eddb047d9d74bd9858
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-29 12:03:40.580190) =========
master: http://buildbot-master119.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-29 12:03:40.580695) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-29 12:03:40.581119) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-29 12:03:40.760266) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-29 12:03:40.760663) =========
'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-114
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-114
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-114
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
--12:03:42-- 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% 13.78 MB/s
12:03:42 (13.78 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=1.292000
========= master_lag: 0.04 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-29 12:03:42.087832) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 15 secs) (at 2016-10-29 12:03:42.088181) =========
'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-114
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-114
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-114
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
program finished with exit code 0
elapsedTime=15.401000
========= master_lag: 0.03 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 15 secs) (at 2016-10-29 12:03:57.520637) =========
========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-10-29 12:03:57.521202) =========
'bash' '-c' u'python archiver_client.py mozharness --repo integration/fx-team --rev 969c3295d3aa77931cca26eddb047d9d74bd9858 --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 969c3295d3aa77931cca26eddb047d9d74bd9858 --destination scripts --debug']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-114
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-114
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-114
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-29 12:03:58,355 truncating revision to first 12 chars
2016-10-29 12:03:58,355 Setting DEBUG logging.
2016-10-29 12:03:58,355 attempt 1/10
2016-10-29 12:03:58,355 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/969c3295d3aa?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2016-10-29 12:04:00,944 unpacking tar archive at: fx-team-969c3295d3aa/testing/mozharness/
2016-10-29 12:04:01,071 skipping symlink on windows: configs/single_locale/linux32.py
program finished with exit code 0
elapsedTime=3.429000
========= master_lag: 0.06 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-10-29 12:04:01.008492) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-29 12:04:01.008849) =========
script_repo_revision: 969c3295d3aa77931cca26eddb047d9d74bd9858
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-29 12:04:01.009293) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-29 12:04:01.009706) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-29 12:04:01.046013) =========
========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 57 secs) (at 2016-10-29 12:04:01.046427) =========
'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-114
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-114
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-114
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
12:04:02 INFO - MultiFileLogger online at 20161029 12:04:02 in C:\slave\test
12:04:02 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
12:04:02 INFO - Dumping config to C:\slave\test\logs\localconfig.json.
12:04:02 INFO - {'append_to_log': False,
12:04:02 INFO - 'base_work_dir': 'C:\\slave\\test',
12:04:02 INFO - 'blob_upload_branch': 'Fx-Team',
12:04:02 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt',
12:04:02 INFO - 'branch': 'Fx-Team',
12:04:02 INFO - 'buildbot_json_path': 'buildprops.json',
12:04:02 INFO - 'config_files': ('talos/windows_config.py',),
12:04:02 INFO - 'default_actions': ('clobber',
12:04:02 INFO - 'read-buildbot-config',
12:04:02 INFO - 'download-and-extract',
12:04:02 INFO - 'populate-webroot',
12:04:02 INFO - 'create-virtualenv',
12:04:02 INFO - 'install',
12:04:02 INFO - 'run-tests'),
12:04:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
12:04:02 INFO - 'download_minidump_stackwalk': True,
12:04:02 INFO - 'download_symbols': 'ondemand',
12:04:02 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python',
12:04:02 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'),
12:04:02 INFO - 'hg': 'c:/mozilla-build/hg/hg',
12:04:02 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python',
12:04:02 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'),
12:04:02 INFO - 'python': 'c:/mozilla-build/python27/python.exe',
12:04:02 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe',
12:04:02 INFO - 'C:/mozilla-build/tooltool.py'),
12:04:02 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe',
12:04:02 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')},
12:04:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
12:04:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
12:04:02 INFO - 'installer_path': 'installer.exe',
12:04:02 INFO - 'log_level': 'info',
12:04:02 INFO - 'log_name': 'talos',
12:04:02 INFO - 'log_to_console': True,
12:04:02 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe',
12:04:02 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe',
12:04:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest',
12:04:02 INFO - 'opt_config_files': (),
12:04:02 INFO - 'pip_index': False,
12:04:02 INFO - 'sps_profile': False,
12:04:02 INFO - 'sps_profile_interval': 0,
12:04:02 INFO - 'suite': 'dromaeojs',
12:04:02 INFO - 'system_bits': '32',
12:04:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
12:04:02 INFO - 'title': 'T-W732-IX-114',
12:04:02 INFO - 'use_talos_json': True,
12:04:02 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'),
12:04:02 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv',
12:04:02 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll',
12:04:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
12:04:02 INFO - 'work_dir': 'build'}
12:04:02 INFO - [mozharness: 2016-10-29 19:04:02.090000Z] Running clobber step.
12:04:02 INFO - Running pre-action listener: _resource_record_pre_action
12:04:02 INFO - Running main action method: clobber
12:04:02 INFO - rmtree: C:\slave\test\build
12:04:02 INFO - Using _rmtree_windows ...
12:04:02 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1
12:06:15 INFO - Running post-action listener: _resource_record_post_action
12:06:15 INFO - [mozharness: 2016-10-29 19:06:15.622000Z] Finished clobber step (success)
12:06:15 INFO - [mozharness: 2016-10-29 19:06:15.622000Z] Running read-buildbot-config step.
12:06:15 INFO - Running pre-action listener: _resource_record_pre_action
12:06:15 INFO - Running main action method: read_buildbot_config
12:06:15 INFO - Using buildbot properties:
12:06:15 INFO - {
12:06:15 INFO - "project": "",
12:06:15 INFO - "product": "firefox",
12:06:15 INFO - "script_repo_revision": "production",
12:06:15 INFO - "builddir": "fx-team_win7_ix_test-dromaeojs",
12:06:15 INFO - "repository": "",
12:06:15 INFO - "buildername": "Windows 7 32-bit fx-team pgo talos dromaeojs",
12:06:15 INFO - "buildid": "20161029120230",
12:06:15 INFO - "stage_platform": "win32-pgo",
12:06:15 INFO - "basedir": "C:\\slave\\test",
12:06:15 INFO - "buildnumber": 55,
12:06:15 INFO - "platform": "win7_ix",
12:06:15 INFO - "master": "http://buildbot-master119.bb.releng.scl3.mozilla.com:8201/",
12:06:15 INFO - "slavebuilddir": "test",
12:06:15 INFO - "branch": "fx-team",
12:06:15 INFO - "revision": "969c3295d3aa77931cca26eddb047d9d74bd9858",
12:06:15 INFO - "slavename": "t-w732-ix-114",
12:06:15 INFO - "builduid": "a93d09fecd474b86919849bb52f9435b",
12:06:15 INFO - "repo_path": "integration/fx-team"
12:06:15 INFO - }
12:06:15 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477758605/firefox-52.0a1.en-US.win32.test_packages.json.
12:06:15 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477758605/firefox-52.0a1.en-US.win32.zip.
12:06:15 INFO - Running post-action listener: _resource_record_post_action
12:06:15 INFO - [mozharness: 2016-10-29 19:06:15.625000Z] Finished read-buildbot-config step (success)
12:06:15 INFO - [mozharness: 2016-10-29 19:06:15.625000Z] Running download-and-extract step.
12:06:15 INFO - Running pre-action listener: _resource_record_pre_action
12:06:15 INFO - Running main action method: download_and_extract
12:06:15 INFO - mkdir: C:\slave\test\build\tests
12:06:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:06:15 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477758605/firefox-52.0a1.en-US.win32.test_packages.json
12:06:15 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477758605/firefox-52.0a1.en-US.win32.test_packages.json to C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json
12:06:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477758605/firefox-52.0a1.en-US.win32.test_packages.json', 'file_name': 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.test_packages.json'}, attempt #1
12:06:16 INFO - Downloaded 1218 bytes.
12:06:16 INFO - Reading from file C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json
12:06:16 INFO - Using the following test package requirements:
12:06:16 INFO - {u'common': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
12:06:16 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
12:06:16 INFO - u'firefox-52.0a1.en-US.win32.cppunittest.tests.zip'],
12:06:16 INFO - u'gtest': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
12:06:16 INFO - u'jittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
12:06:16 INFO - u'jsshell-win32.zip'],
12:06:16 INFO - u'mochitest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
12:06:16 INFO - u'firefox-52.0a1.en-US.win32.mochitest.tests.zip'],
12:06:16 INFO - u'mozbase': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
12:06:16 INFO - u'reftest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
12:06:16 INFO - u'firefox-52.0a1.en-US.win32.reftest.tests.zip'],
12:06:16 INFO - u'talos': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
12:06:16 INFO - u'firefox-52.0a1.en-US.win32.talos.tests.zip'],
12:06:16 INFO - u'web-platform': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
12:06:16 INFO - u'firefox-52.0a1.en-US.win32.web-platform.tests.zip'],
12:06:16 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
12:06:16 INFO - u'firefox-52.0a1.en-US.win32.xpcshell.tests.zip']}
12:06:16 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip'] for test suite category: common
12:06:16 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/1477758605/firefox-52.0a1.en-US.win32.common.tests.zip
12:06:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477758605/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1
12:06:16 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477758605/firefox-52.0a1.en-US.win32.common.tests.zip into memory
12:06:16 INFO - Http code: 200
12:06:16 INFO - via: 1.1 37af14ee951b12568cf1cc160d08abe6.cloudfront.net (CloudFront)
12:06:16 INFO - x-amz-cf-id: 2yxHVbMm2JAzXi95HgP1maAOFXLaul2T7pMDCSbRpNxzToIOeuCd2Q==
12:06:16 INFO - x-amz-replication-status: PENDING
12:06:16 INFO - x-amz-version-id: Zn4Uhht9pbBjHKSXYtskTeifL3qJ91WB
12:06:16 INFO - Expected file size: 17149828
12:06:16 INFO - Obtained file size: 17149828
12:06:39 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip', u'firefox-52.0a1.en-US.win32.talos.tests.zip'] for test suite category: talos
12:06:39 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/1477758605/firefox-52.0a1.en-US.win32.common.tests.zip
12:06:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477758605/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1
12:06:39 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477758605/firefox-52.0a1.en-US.win32.common.tests.zip into memory
12:06:39 INFO - Http code: 200
12:06:39 INFO - via: 1.1 c54d7f08e2f3dab1918454910cc8aad0.cloudfront.net (CloudFront)
12:06:39 INFO - x-amz-cf-id: lbtRuZJbpGCRm3DMY-sf3jom-LB_DZyeHNCU4DZh2PuyrDLrg5HItA==
12:06:39 INFO - x-amz-replication-status: PENDING
12:06:39 INFO - x-amz-version-id: Zn4Uhht9pbBjHKSXYtskTeifL3qJ91WB
12:06:40 INFO - Expected file size: 17149828
12:06:40 INFO - Obtained file size: 17149828
12:06:50 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/1477758605/firefox-52.0a1.en-US.win32.talos.tests.zip
12:06:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477758605/firefox-52.0a1.en-US.win32.talos.tests.zip'}, attempt #1
12:06:50 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477758605/firefox-52.0a1.en-US.win32.talos.tests.zip into memory
12:06:50 INFO - Http code: 200
12:06:50 INFO - via: 1.1 d8f42fc9558e3e49ebfdf8834baeb756.cloudfront.net (CloudFront)
12:06:50 INFO - x-amz-cf-id: T20jbUFDBqd1jLMqphSxaRC0kxuBbndrlVp2x1i5-jiOXgaouUW1CA==
12:06:50 INFO - x-amz-replication-status: PENDING
12:06:50 INFO - x-amz-version-id: sgEUPMaUgUHEoYvCFrx8UWd2xyzBinC0
12:06:51 INFO - Expected file size: 14409038
12:06:51 INFO - Obtained file size: 14409038
12:07:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:07:01 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477758605/firefox-52.0a1.en-US.win32.zip
12:07:01 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477758605/firefox-52.0a1.en-US.win32.zip to C:\slave\test\build\firefox-52.0a1.en-US.win32.zip
12:07:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477758605/firefox-52.0a1.en-US.win32.zip', 'file_name': 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.zip'}, attempt #1
12:07:04 INFO - Downloaded 54288864 bytes.
12:07:04 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477758605/firefox-52.0a1.en-US.win32.zip
12:07:04 INFO - mkdir: C:\slave\test\properties
12:07:04 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url
12:07:04 INFO - Writing to file C:\slave\test\properties\build_url
12:07:04 INFO - Contents:
12:07:04 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477758605/firefox-52.0a1.en-US.win32.zip
12:07:04 INFO - Running post-action listener: _resource_record_post_action
12:07:04 INFO - Running post-action listener: set_extra_try_arguments
12:07:04 INFO - [mozharness: 2016-10-29 19:07:04.770000Z] Finished download-and-extract step (success)
12:07:04 INFO - [mozharness: 2016-10-29 19:07:04.771000Z] Running populate-webroot step.
12:07:04 INFO - Running pre-action listener: _resource_record_pre_action
12:07:04 INFO - Running main action method: populate_webroot
12:07:04 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']},
12:07:04 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']},
12:07:04 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome',
12:07:04 INFO - u'--tppagecycles',
12:07:04 INFO - u'7'],
12:07:04 INFO - u'tests': [u'tsvgm']}},
12:07:04 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'],
12:07:04 INFO - u'tests': [u'tresize', u'tcanvasmark']},
12:07:04 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']},
12:07:04 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'],
12:07:04 INFO - u'tests': [u'dromaeo_css', u'kraken']},
12:07:04 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']},
12:07:04 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
12:07:04 INFO - u'talos_options': [u'--disable-e10s'],
12:07:04 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
12:07:04 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
12:07:04 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
12:07:04 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
12:07:04 INFO - u'talos_options': [u'--disable-e10s'],
12:07:04 INFO - u'tests': [u'damp', u'tps']},
12:07:04 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
12:07:04 INFO - u'tests': [u'damp', u'tps']},
12:07:04 INFO - u'g3': {u'talos_options': [u'--disable-e10s'],
12:07:04 INFO - u'tests': [u'dromaeo_dom']},
12:07:04 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']},
12:07:04 INFO - u'g4': {u'talos_options': [u'--disable-e10s'],
12:07:04 INFO - u'tests': [u'basic_compositor_video']},
12:07:04 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']},
12:07:04 INFO - u'other': {u'talos_options': [u'--disable-e10s'],
12:07:04 INFO - u'tests': [u'a11yr',
12:07:04 INFO - u'ts_paint',
12:07:04 INFO - u'tpaint',
12:07:04 INFO - u'sessionrestore',
12:07:04 INFO - u'sessionrestore_no_auto_restore',
12:07:04 INFO - u'tabpaint']},
12:07:04 INFO - u'other-e10s': {u'tests': [u'a11yr',
12:07:04 INFO - u'ts_paint',
12:07:04 INFO - u'tpaint',
12:07:04 INFO - u'sessionrestore',
12:07:04 INFO - u'sessionrestore_no_auto_restore',
12:07:04 INFO - u'tabpaint']},
12:07:04 INFO - u'other-e10s_l64': {u'tests': [u'a11yr',
12:07:04 INFO - u'ts_paint',
12:07:04 INFO - u'tpaint',
12:07:04 INFO - u'sessionrestore',
12:07:04 INFO - u'sessionrestore_no_auto_restore',
12:07:04 INFO - u'tabpaint']},
12:07:04 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr',
12:07:04 INFO - u'ts_paint',
12:07:04 INFO - u'tpaint',
12:07:04 INFO - u'sessionrestore',
12:07:04 INFO - u'sessionrestore_no_auto_restore',
12:07:04 INFO - u'tabpaint']},
12:07:04 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'],
12:07:04 INFO - u'tests': [u'a11yr',
12:07:04 INFO - u'ts_paint',
12:07:04 INFO - u'tpaint',
12:07:04 INFO - u'sessionrestore',
12:07:04 INFO - u'sessionrestore_no_auto_restore',
12:07:04 INFO - u'tabpaint']},
12:07:04 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'],
12:07:04 INFO - u'tests': [u'a11yr',
12:07:04 INFO - u'ts_paint',
12:07:04 INFO - u'tpaint',
12:07:04 INFO - u'sessionrestore',
12:07:04 INFO - u'sessionrestore_no_auto_restore',
12:07:04 INFO - u'tabpaint']},
12:07:04 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'],
12:07:04 INFO - u'tests': [u'tsvgx',
12:07:04 INFO - u'tsvgr_opacity',
12:07:04 INFO - u'tart',
12:07:04 INFO - u'tscrollx',
12:07:04 INFO - u'cart']},
12:07:04 INFO - u'svgr-e10s': {u'tests': [u'tsvgx',
12:07:04 INFO - u'tsvgr_opacity',
12:07:04 INFO - u'tart',
12:07:04 INFO - u'tscrollx',
12:07:04 INFO - u'cart']},
12:07:04 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
12:07:04 INFO - u'talos_options': [u'--disable-e10s'],
12:07:04 INFO - u'tests': [u'tp5o']},
12:07:04 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
12:07:04 INFO - u'tests': [u'tp5o']},
12:07:04 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
12:07:04 INFO - u'talos_options': [u'--disable-e10s',
12:07:04 INFO - u'--xperf_path',
12:07:04 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
12:07:04 INFO - u'tests': [u'tp5n']},
12:07:04 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
12:07:04 INFO - u'talos_options': [u'--xperf_path',
12:07:04 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
12:07:04 INFO - u'tests': [u'tp5n']}},
12:07:04 INFO - u'talos.zip': {u'path': u'',
12:07:04 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}}
12:07:04 INFO - Running post-action listener: _resource_record_post_action
12:07:04 INFO - [mozharness: 2016-10-29 19:07:04.785000Z] Finished populate-webroot step (success)
12:07:04 INFO - [mozharness: 2016-10-29 19:07:04.785000Z] Running create-virtualenv step.
12:07:04 INFO - Running pre-action listener: _resource_record_pre_action
12:07:04 INFO - Running main action method: create_virtualenv
12:07:04 INFO - Creating virtualenv C:\slave\test\build/venv
12:07:04 INFO - mkdir: C:\slave\test\build\venv\Scripts
12:07:04 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll
12:07:04 WARNING - Can't copy c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll: [Errno 2] No such file or directory: 'c:/mozilla-build/python27/python27.dll'!
12:07:04 INFO - Running command: ['c:/mozilla-build/python27/python.exe', 'c:/mozilla-build/buildbotve/virtualenv.py', '--no-site-packages', '--distribute', 'C:\\slave\\test\\build/venv'] in C:\slave\test\build
12:07:04 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv
12:07:10 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe
12:07:16 INFO - Installing distribute......................................................................................................................................................................................done.
12:07:17 INFO - Return code: 0
12:07:17 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version']
12:07:17 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version
12:07:18 INFO - Reading from file tmpfile_stdout
12:07:18 INFO - Output received:
12:07:18 INFO - pip 0.8.2 from c:\slave\test\build\venv\lib\site-packages\pip-0.8.2-py2.7.egg (python 2.7)
12:07:18 INFO - Using _rmtree_windows ...
12:07:18 INFO - Using _rmtree_windows ...
12:07:18 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv
12:07:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:07:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:07:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:07:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:07:18 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01831640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018D9E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017DB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018F8E90>, '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 0x012FA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019AE520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-114', 'USERDOMAIN': 'T-W732-IX-114', '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-114', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
12:07:18 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in C:\slave\test\build
12:07:18 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5
12:07:18 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
12:07:18 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
12:07:18 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
12:07:18 INFO - 'COMPUTERNAME': 'T-W732-IX-114',
12:07:18 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
12:07:18 INFO - 'CYGWINBASE': 'C:\\cygwin',
12:07:18 INFO - 'DCLOCATION': 'SCL3',
12:07:18 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
12:07:18 INFO - 'FP_NO_HOST_CHECK': 'NO',
12:07:18 INFO - 'HOMEDRIVE': 'C:',
12:07:18 INFO - 'HOMEPATH': '\\Users\\cltbld',
12:07:18 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
12:07:18 INFO - 'KTS_VERSION': '1.19c',
12:07:18 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
12:07:18 INFO - 'LOGONSERVER': '\\\\T-W732-IX-114',
12:07:18 INFO - 'MONDIR': 'C:\\Monitor_config\\',
12:07:18 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
12:07:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:07:18 INFO - 'MOZ_NO_REMOTE': '1',
12:07:18 INFO - 'NO_EM_RESTART': '1',
12:07:18 INFO - 'NUMBER_OF_PROCESSORS': '8',
12:07:18 INFO - 'OS': 'Windows_NT',
12:07:18 INFO - 'OURDRIVE': 'C:',
12:07:18 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
12:07:18 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
12:07:18 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
12:07:18 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
12:07:18 INFO - 'PROCESSOR_LEVEL': '6',
12:07:18 INFO - 'PROCESSOR_REVISION': '1e05',
12:07:18 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
12:07:18 INFO - 'PROGRAMFILES': 'C:\\Program Files',
12:07:18 INFO - 'PROMPT': '$P$G',
12:07:18 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
12:07:18 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
12:07:18 INFO - 'PUBLIC': 'C:\\Users\\Public',
12:07:18 INFO - 'PWD': 'C:\\slave\\test',
12:07:18 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
12:07:18 INFO - 'SLAVEDIR': 'C:\\slave\\',
12:07:18 INFO - 'SYSTEMDRIVE': 'C:',
12:07:18 INFO - 'SYSTEMROOT': 'C:\\windows',
12:07:18 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:07:18 INFO - 'TEST1': 'testie',
12:07:18 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:07:18 INFO - 'USERDOMAIN': 'T-W732-IX-114',
12:07:18 INFO - 'USERNAME': 'cltbld',
12:07:18 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
12:07:18 INFO - 'WINDIR': 'C:\\windows',
12:07:18 INFO - 'WINDOWS_TRACING_FLAGS': '3',
12:07:18 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
12:07:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
12:07:19 INFO - Ignoring indexes: http://pypi.python.org/simple/
12:07:19 INFO - Downloading/unpacking pip>=1.5
12:07:23 INFO - Running setup.py egg_info for package pip
12:07:23 INFO - warning: no files found matching 'pip\cacert.pem'
12:07:23 INFO - warning: no files found matching '*.html' under directory 'docs'
12:07:23 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
12:07:23 INFO - no previously-included directories found matching 'docs\_build\_sources'
12:07:23 INFO - Installing collected packages: pip
12:07:23 INFO - Found existing installation: pip 0.8.2
12:07:23 INFO - Uninstalling pip:
12:07:23 INFO - Successfully uninstalled pip
12:07:23 INFO - Running setup.py install for pip
12:07:23 INFO - warning: no files found matching 'pip\cacert.pem'
12:07:23 INFO - warning: no files found matching '*.html' under directory 'docs'
12:07:23 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
12:07:23 INFO - no previously-included directories found matching 'docs\_build\_sources'
12:07:23 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts
12:07:23 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts
12:07:23 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts
12:07:23 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts
12:07:23 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts
12:07:23 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts
12:07:23 INFO - Successfully installed pip
12:07:23 INFO - Cleaning up...
12:07:23 INFO - Return code: 0
12:07:23 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv
12:07:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:07:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:07:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:07:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:07:23 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 0x01831640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018D9E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017DB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018F8E90>, '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 0x012FA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019AE520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-114', 'USERDOMAIN': 'T-W732-IX-114', '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-114', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
12:07:23 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in C:\slave\test\build
12:07:23 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1
12:07:23 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
12:07:23 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
12:07:23 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
12:07:23 INFO - 'COMPUTERNAME': 'T-W732-IX-114',
12:07:23 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
12:07:23 INFO - 'CYGWINBASE': 'C:\\cygwin',
12:07:23 INFO - 'DCLOCATION': 'SCL3',
12:07:23 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
12:07:23 INFO - 'FP_NO_HOST_CHECK': 'NO',
12:07:23 INFO - 'HOMEDRIVE': 'C:',
12:07:23 INFO - 'HOMEPATH': '\\Users\\cltbld',
12:07:23 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
12:07:23 INFO - 'KTS_VERSION': '1.19c',
12:07:23 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
12:07:23 INFO - 'LOGONSERVER': '\\\\T-W732-IX-114',
12:07:23 INFO - 'MONDIR': 'C:\\Monitor_config\\',
12:07:23 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
12:07:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:07:23 INFO - 'MOZ_NO_REMOTE': '1',
12:07:23 INFO - 'NO_EM_RESTART': '1',
12:07:23 INFO - 'NUMBER_OF_PROCESSORS': '8',
12:07:23 INFO - 'OS': 'Windows_NT',
12:07:23 INFO - 'OURDRIVE': 'C:',
12:07:23 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
12:07:23 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
12:07:23 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
12:07:23 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
12:07:23 INFO - 'PROCESSOR_LEVEL': '6',
12:07:23 INFO - 'PROCESSOR_REVISION': '1e05',
12:07:23 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
12:07:23 INFO - 'PROGRAMFILES': 'C:\\Program Files',
12:07:23 INFO - 'PROMPT': '$P$G',
12:07:23 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
12:07:23 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
12:07:23 INFO - 'PUBLIC': 'C:\\Users\\Public',
12:07:23 INFO - 'PWD': 'C:\\slave\\test',
12:07:23 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
12:07:23 INFO - 'SLAVEDIR': 'C:\\slave\\',
12:07:23 INFO - 'SYSTEMDRIVE': 'C:',
12:07:23 INFO - 'SYSTEMROOT': 'C:\\windows',
12:07:23 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:07:23 INFO - 'TEST1': 'testie',
12:07:23 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:07:23 INFO - 'USERDOMAIN': 'T-W732-IX-114',
12:07:23 INFO - 'USERNAME': 'cltbld',
12:07:23 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
12:07:23 INFO - 'WINDIR': 'C:\\windows',
12:07:23 INFO - 'WINDOWS_TRACING_FLAGS': '3',
12:07:23 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
12:07:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
12:07:25 INFO - Ignoring indexes: https://pypi.python.org/simple/
12:07:25 INFO - Downloading/unpacking psutil>=3.1.1
12:07:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
12:07:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
12:07:26 INFO - Installing collected packages: psutil
12:07:27 INFO - Successfully installed psutil
12:07:27 INFO - Cleaning up...
12:07:27 INFO - Return code: 0
12:07:27 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv
12:07:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:07:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:07:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:07:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:07:27 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 0x01831640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018D9E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017DB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018F8E90>, '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 0x012FA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019AE520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-114', 'USERDOMAIN': 'T-W732-IX-114', '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-114', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
12:07:27 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in C:\slave\test\build
12:07:27 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3
12:07:27 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
12:07:27 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
12:07:27 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
12:07:27 INFO - 'COMPUTERNAME': 'T-W732-IX-114',
12:07:27 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
12:07:27 INFO - 'CYGWINBASE': 'C:\\cygwin',
12:07:27 INFO - 'DCLOCATION': 'SCL3',
12:07:27 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
12:07:27 INFO - 'FP_NO_HOST_CHECK': 'NO',
12:07:27 INFO - 'HOMEDRIVE': 'C:',
12:07:27 INFO - 'HOMEPATH': '\\Users\\cltbld',
12:07:27 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
12:07:27 INFO - 'KTS_VERSION': '1.19c',
12:07:27 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
12:07:27 INFO - 'LOGONSERVER': '\\\\T-W732-IX-114',
12:07:27 INFO - 'MONDIR': 'C:\\Monitor_config\\',
12:07:27 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
12:07:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:07:27 INFO - 'MOZ_NO_REMOTE': '1',
12:07:27 INFO - 'NO_EM_RESTART': '1',
12:07:27 INFO - 'NUMBER_OF_PROCESSORS': '8',
12:07:27 INFO - 'OS': 'Windows_NT',
12:07:27 INFO - 'OURDRIVE': 'C:',
12:07:27 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
12:07:27 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
12:07:27 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
12:07:27 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
12:07:27 INFO - 'PROCESSOR_LEVEL': '6',
12:07:27 INFO - 'PROCESSOR_REVISION': '1e05',
12:07:27 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
12:07:27 INFO - 'PROGRAMFILES': 'C:\\Program Files',
12:07:27 INFO - 'PROMPT': '$P$G',
12:07:27 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
12:07:27 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
12:07:27 INFO - 'PUBLIC': 'C:\\Users\\Public',
12:07:27 INFO - 'PWD': 'C:\\slave\\test',
12:07:27 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
12:07:27 INFO - 'SLAVEDIR': 'C:\\slave\\',
12:07:27 INFO - 'SYSTEMDRIVE': 'C:',
12:07:27 INFO - 'SYSTEMROOT': 'C:\\windows',
12:07:27 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:07:27 INFO - 'TEST1': 'testie',
12:07:27 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:07:27 INFO - 'USERDOMAIN': 'T-W732-IX-114',
12:07:27 INFO - 'USERNAME': 'cltbld',
12:07:27 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
12:07:27 INFO - 'WINDIR': 'C:\\windows',
12:07:27 INFO - 'WINDOWS_TRACING_FLAGS': '3',
12:07:27 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
12:07:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
12:07:27 INFO - Ignoring indexes: https://pypi.python.org/simple/
12:07:27 INFO - Downloading/unpacking mozsystemmonitor==0.3
12:07:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
12:07:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
12:07:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz
12:07:28 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor
12:07:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in c:\slave\test\build\venv\lib\site-packages (from mozsystemmonitor==0.3)
12:07:28 INFO - Installing collected packages: mozsystemmonitor
12:07:28 INFO - Running setup.py install for mozsystemmonitor
12:07:28 INFO - Successfully installed mozsystemmonitor
12:07:28 INFO - Cleaning up...
12:07:29 INFO - Return code: 0
12:07:29 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv
12:07:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:07:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:07:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:07:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:07:29 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 0x01831640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018D9E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017DB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018F8E90>, '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 0x012FA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019AE520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-114', 'USERDOMAIN': 'T-W732-IX-114', '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-114', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
12:07:29 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in C:\slave\test\build
12:07:29 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1
12:07:29 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
12:07:29 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
12:07:29 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
12:07:29 INFO - 'COMPUTERNAME': 'T-W732-IX-114',
12:07:29 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
12:07:29 INFO - 'CYGWINBASE': 'C:\\cygwin',
12:07:29 INFO - 'DCLOCATION': 'SCL3',
12:07:29 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
12:07:29 INFO - 'FP_NO_HOST_CHECK': 'NO',
12:07:29 INFO - 'HOMEDRIVE': 'C:',
12:07:29 INFO - 'HOMEPATH': '\\Users\\cltbld',
12:07:29 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
12:07:29 INFO - 'KTS_VERSION': '1.19c',
12:07:29 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
12:07:29 INFO - 'LOGONSERVER': '\\\\T-W732-IX-114',
12:07:29 INFO - 'MONDIR': 'C:\\Monitor_config\\',
12:07:29 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
12:07:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:07:29 INFO - 'MOZ_NO_REMOTE': '1',
12:07:29 INFO - 'NO_EM_RESTART': '1',
12:07:29 INFO - 'NUMBER_OF_PROCESSORS': '8',
12:07:29 INFO - 'OS': 'Windows_NT',
12:07:29 INFO - 'OURDRIVE': 'C:',
12:07:29 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
12:07:29 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
12:07:29 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
12:07:29 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
12:07:29 INFO - 'PROCESSOR_LEVEL': '6',
12:07:29 INFO - 'PROCESSOR_REVISION': '1e05',
12:07:29 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
12:07:29 INFO - 'PROGRAMFILES': 'C:\\Program Files',
12:07:29 INFO - 'PROMPT': '$P$G',
12:07:29 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
12:07:29 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
12:07:29 INFO - 'PUBLIC': 'C:\\Users\\Public',
12:07:29 INFO - 'PWD': 'C:\\slave\\test',
12:07:29 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
12:07:29 INFO - 'SLAVEDIR': 'C:\\slave\\',
12:07:29 INFO - 'SYSTEMDRIVE': 'C:',
12:07:29 INFO - 'SYSTEMROOT': 'C:\\windows',
12:07:29 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:07:29 INFO - 'TEST1': 'testie',
12:07:29 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:07:29 INFO - 'USERDOMAIN': 'T-W732-IX-114',
12:07:29 INFO - 'USERNAME': 'cltbld',
12:07:29 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
12:07:29 INFO - 'WINDIR': 'C:\\windows',
12:07:29 INFO - 'WINDOWS_TRACING_FLAGS': '3',
12:07:29 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
12:07:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
12:07:29 INFO - Ignoring indexes: https://pypi.python.org/simple/
12:07:29 INFO - Downloading/unpacking jsonschema==2.5.1
12:07:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
12:07:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
12:07:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl
12:07:30 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1)
12:07:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
12:07:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
12:07:30 INFO - Downloading functools32-3.2.3-2.tar.gz
12:07:30 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32
12:07:30 INFO - warning: no files found matching '*.txt'
12:07:30 INFO - no previously-included directories found matching 'build'
12:07:30 INFO - no previously-included directories found matching 'dist'
12:07:30 INFO - no previously-included directories found matching '.git*'
12:07:30 INFO - Installing collected packages: jsonschema, functools32
12:07:30 INFO - Running setup.py install for functools32
12:07:30 INFO - warning: no files found matching '*.txt'
12:07:30 INFO - no previously-included directories found matching 'build'
12:07:30 INFO - no previously-included directories found matching 'dist'
12:07:30 INFO - no previously-included directories found matching '.git*'
12:07:31 INFO - Successfully installed jsonschema functools32
12:07:31 INFO - Cleaning up...
12:07:31 INFO - Return code: 0
12:07:31 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv
12:07:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:07:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:07:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:07:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:07:31 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 0x01831640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018D9E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017DB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018F8E90>, '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 0x012FA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019AE520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-114', 'USERDOMAIN': 'T-W732-IX-114', '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-114', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
12:07:31 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in C:\slave\test\build
12:07:31 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2
12:07:31 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
12:07:31 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
12:07:31 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
12:07:31 INFO - 'COMPUTERNAME': 'T-W732-IX-114',
12:07:31 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
12:07:31 INFO - 'CYGWINBASE': 'C:\\cygwin',
12:07:31 INFO - 'DCLOCATION': 'SCL3',
12:07:31 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
12:07:31 INFO - 'FP_NO_HOST_CHECK': 'NO',
12:07:31 INFO - 'HOMEDRIVE': 'C:',
12:07:31 INFO - 'HOMEPATH': '\\Users\\cltbld',
12:07:31 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
12:07:31 INFO - 'KTS_VERSION': '1.19c',
12:07:31 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
12:07:31 INFO - 'LOGONSERVER': '\\\\T-W732-IX-114',
12:07:31 INFO - 'MONDIR': 'C:\\Monitor_config\\',
12:07:31 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
12:07:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:07:31 INFO - 'MOZ_NO_REMOTE': '1',
12:07:31 INFO - 'NO_EM_RESTART': '1',
12:07:31 INFO - 'NUMBER_OF_PROCESSORS': '8',
12:07:31 INFO - 'OS': 'Windows_NT',
12:07:31 INFO - 'OURDRIVE': 'C:',
12:07:31 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
12:07:31 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
12:07:31 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
12:07:31 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
12:07:31 INFO - 'PROCESSOR_LEVEL': '6',
12:07:31 INFO - 'PROCESSOR_REVISION': '1e05',
12:07:31 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
12:07:31 INFO - 'PROGRAMFILES': 'C:\\Program Files',
12:07:31 INFO - 'PROMPT': '$P$G',
12:07:31 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
12:07:31 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
12:07:31 INFO - 'PUBLIC': 'C:\\Users\\Public',
12:07:31 INFO - 'PWD': 'C:\\slave\\test',
12:07:31 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
12:07:31 INFO - 'SLAVEDIR': 'C:\\slave\\',
12:07:31 INFO - 'SYSTEMDRIVE': 'C:',
12:07:31 INFO - 'SYSTEMROOT': 'C:\\windows',
12:07:31 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:07:31 INFO - 'TEST1': 'testie',
12:07:31 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:07:31 INFO - 'USERDOMAIN': 'T-W732-IX-114',
12:07:31 INFO - 'USERNAME': 'cltbld',
12:07:31 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
12:07:31 INFO - 'WINDIR': 'C:\\windows',
12:07:31 INFO - 'WINDOWS_TRACING_FLAGS': '3',
12:07:31 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
12:07:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
12:07:31 INFO - Ignoring indexes: https://pypi.python.org/simple/
12:07:31 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages
12:07:31 INFO - Cleaning up...
12:07:31 INFO - Return code: 0
12:07:31 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv
12:07:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:07:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:07:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:07:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:07:31 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 0x01831640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018D9E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017DB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018F8E90>, '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 0x012FA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019AE520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-114', 'USERDOMAIN': 'T-W732-IX-114', '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-114', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
12:07:31 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in C:\slave\test\build
12:07:31 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4
12:07:31 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
12:07:31 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
12:07:31 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
12:07:31 INFO - 'COMPUTERNAME': 'T-W732-IX-114',
12:07:31 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
12:07:31 INFO - 'CYGWINBASE': 'C:\\cygwin',
12:07:31 INFO - 'DCLOCATION': 'SCL3',
12:07:31 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
12:07:31 INFO - 'FP_NO_HOST_CHECK': 'NO',
12:07:31 INFO - 'HOMEDRIVE': 'C:',
12:07:31 INFO - 'HOMEPATH': '\\Users\\cltbld',
12:07:31 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
12:07:31 INFO - 'KTS_VERSION': '1.19c',
12:07:31 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
12:07:31 INFO - 'LOGONSERVER': '\\\\T-W732-IX-114',
12:07:31 INFO - 'MONDIR': 'C:\\Monitor_config\\',
12:07:31 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
12:07:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:07:31 INFO - 'MOZ_NO_REMOTE': '1',
12:07:31 INFO - 'NO_EM_RESTART': '1',
12:07:31 INFO - 'NUMBER_OF_PROCESSORS': '8',
12:07:31 INFO - 'OS': 'Windows_NT',
12:07:31 INFO - 'OURDRIVE': 'C:',
12:07:31 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
12:07:31 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
12:07:31 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
12:07:31 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
12:07:31 INFO - 'PROCESSOR_LEVEL': '6',
12:07:31 INFO - 'PROCESSOR_REVISION': '1e05',
12:07:31 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
12:07:31 INFO - 'PROGRAMFILES': 'C:\\Program Files',
12:07:31 INFO - 'PROMPT': '$P$G',
12:07:31 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
12:07:31 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
12:07:31 INFO - 'PUBLIC': 'C:\\Users\\Public',
12:07:31 INFO - 'PWD': 'C:\\slave\\test',
12:07:31 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
12:07:31 INFO - 'SLAVEDIR': 'C:\\slave\\',
12:07:31 INFO - 'SYSTEMDRIVE': 'C:',
12:07:31 INFO - 'SYSTEMROOT': 'C:\\windows',
12:07:31 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:07:31 INFO - 'TEST1': 'testie',
12:07:31 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:07:31 INFO - 'USERDOMAIN': 'T-W732-IX-114',
12:07:31 INFO - 'USERNAME': 'cltbld',
12:07:31 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
12:07:31 INFO - 'WINDIR': 'C:\\windows',
12:07:31 INFO - 'WINDOWS_TRACING_FLAGS': '3',
12:07:31 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
12:07:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
12:07:32 INFO - Ignoring indexes: https://pypi.python.org/simple/
12:07:32 INFO - Downloading/unpacking blobuploader==1.2.4
12:07:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
12:07:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
12:07:33 INFO - Downloading blobuploader-1.2.4.tar.gz
12:07:33 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader
12:07:33 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4)
12:07:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
12:07:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
12:07:33 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests
12:07:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4)
12:07:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
12:07:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
12:07:34 INFO - Downloading docopt-0.6.1.tar.gz
12:07:34 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt
12:07:34 INFO - Installing collected packages: blobuploader, requests, docopt
12:07:34 INFO - Running setup.py install for blobuploader
12:07:34 INFO - Running setup.py install for requests
12:07:34 INFO - Running setup.py install for docopt
12:07:35 INFO - Successfully installed blobuploader requests docopt
12:07:35 INFO - Cleaning up...
12:07:36 INFO - Return code: 0
12:07:36 INFO - Installing None into virtualenv C:\slave\test\build/venv
12:07:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:07:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:07:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:07:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:07:36 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 0x01831640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018D9E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017DB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018F8E90>, '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 0x012FA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019AE520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-114', 'USERDOMAIN': 'T-W732-IX-114', '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-114', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
12:07:36 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--no-deps', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test\build\tests\config
12:07:36 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --no-deps --timeout 120 -r C:\slave\test\build\tests\config\mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub
12:07:36 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
12:07:36 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
12:07:36 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
12:07:36 INFO - 'COMPUTERNAME': 'T-W732-IX-114',
12:07:36 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
12:07:36 INFO - 'CYGWINBASE': 'C:\\cygwin',
12:07:36 INFO - 'DCLOCATION': 'SCL3',
12:07:36 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
12:07:36 INFO - 'FP_NO_HOST_CHECK': 'NO',
12:07:36 INFO - 'HOMEDRIVE': 'C:',
12:07:36 INFO - 'HOMEPATH': '\\Users\\cltbld',
12:07:36 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
12:07:36 INFO - 'KTS_VERSION': '1.19c',
12:07:36 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
12:07:36 INFO - 'LOGONSERVER': '\\\\T-W732-IX-114',
12:07:36 INFO - 'MONDIR': 'C:\\Monitor_config\\',
12:07:36 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
12:07:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:07:36 INFO - 'MOZ_NO_REMOTE': '1',
12:07:36 INFO - 'NO_EM_RESTART': '1',
12:07:36 INFO - 'NUMBER_OF_PROCESSORS': '8',
12:07:36 INFO - 'OS': 'Windows_NT',
12:07:36 INFO - 'OURDRIVE': 'C:',
12:07:36 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
12:07:36 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
12:07:36 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
12:07:36 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
12:07:36 INFO - 'PROCESSOR_LEVEL': '6',
12:07:36 INFO - 'PROCESSOR_REVISION': '1e05',
12:07:36 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
12:07:36 INFO - 'PROGRAMFILES': 'C:\\Program Files',
12:07:36 INFO - 'PROMPT': '$P$G',
12:07:36 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
12:07:36 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
12:07:36 INFO - 'PUBLIC': 'C:\\Users\\Public',
12:07:36 INFO - 'PWD': 'C:\\slave\\test',
12:07:36 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
12:07:36 INFO - 'SLAVEDIR': 'C:\\slave\\',
12:07:36 INFO - 'SYSTEMDRIVE': 'C:',
12:07:36 INFO - 'SYSTEMROOT': 'C:\\windows',
12:07:36 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:07:36 INFO - 'TEST1': 'testie',
12:07:36 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:07:36 INFO - 'USERDOMAIN': 'T-W732-IX-114',
12:07:36 INFO - 'USERNAME': 'cltbld',
12:07:36 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
12:07:36 INFO - 'WINDIR': 'C:\\windows',
12:07:36 INFO - 'WINDOWS_TRACING_FLAGS': '3',
12:07:36 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
12:07:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
12:07:36 INFO - Ignoring indexes: https://pypi.python.org/simple/
12:07:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
12:07:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-u4rcef-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
12:07:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
12:07:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cqnftp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
12:07:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
12:07:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rnj59r-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
12:07:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
12:07:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-muqttj-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
12:07:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
12:07:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vyqsmy-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
12:07:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
12:07:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-teiyng-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
12:07:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
12:07:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bwto6q-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
12:07:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
12:07:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-znqwvr-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
12:07:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
12:07:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-9wlw5r-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
12:07:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
12:07:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-nfxc_a-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
12:07:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
12:07:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-uqkjgp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
12:07:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
12:07:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-db5tel-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
12:07:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
12:07:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mycwqd-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
12:07:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
12:07:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vimpt4-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
12:07:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
12:07:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-d2fmif-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
12:07:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
12:07:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-5yz1lw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
12:07:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
12:07:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-1u6nt4-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
12:07:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
12:07:39 INFO - Running setup.py install for manifestparser
12:07:39 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts
12:07:39 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts
12:07:40 INFO - Running setup.py install for mozcrash
12:07:40 INFO - Running setup.py install for mozdebug
12:07:40 INFO - Running setup.py install for mozdevice
12:07:41 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts
12:07:41 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts
12:07:41 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts
12:07:41 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts
12:07:41 INFO - Running setup.py install for mozfile
12:07:41 INFO - Running setup.py install for mozhttpd
12:07:42 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts
12:07:42 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts
12:07:42 INFO - Running setup.py install for mozinfo
12:07:42 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts
12:07:42 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts
12:07:42 INFO - Running setup.py install for mozInstall
12:07:42 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts
12:07:42 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts
12:07:42 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts
12:07:42 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts
12:07:42 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts
12:07:42 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts
12:07:42 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts
12:07:42 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts
12:07:43 INFO - Running setup.py install for mozleak
12:07:43 INFO - Running setup.py install for mozlog
12:07:44 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts
12:07:44 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts
12:07:44 INFO - Running setup.py install for moznetwork
12:07:44 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts
12:07:44 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts
12:07:45 INFO - Running setup.py install for mozprocess
12:07:45 INFO - Running setup.py install for mozprofile
12:07:45 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts
12:07:45 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts
12:07:45 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts
12:07:45 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts
12:07:45 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts
12:07:45 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts
12:07:46 INFO - Running setup.py install for mozrunner
12:07:46 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts
12:07:46 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts
12:07:46 INFO - Running setup.py install for mozscreenshot
12:07:47 INFO - Running setup.py install for moztest
12:07:47 INFO - Running setup.py install for mozversion
12:07:47 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts
12:07:47 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts
12:07:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion
12:07:48 INFO - Cleaning up...
12:07:48 INFO - Return code: 0
12:07:48 INFO - Installing None into virtualenv C:\slave\test\build/venv
12:07:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:07:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:07:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:07:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:07:48 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 0x01831640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018D9E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017DB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018F8E90>, '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 0x012FA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019AE520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-114', 'USERDOMAIN': 'T-W732-IX-114', '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-114', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
12:07:48 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test\build\tests\config
12:07:48 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 -r C:\slave\test\build\tests\config\mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub
12:07:48 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
12:07:48 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
12:07:48 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
12:07:48 INFO - 'COMPUTERNAME': 'T-W732-IX-114',
12:07:48 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
12:07:48 INFO - 'CYGWINBASE': 'C:\\cygwin',
12:07:48 INFO - 'DCLOCATION': 'SCL3',
12:07:48 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
12:07:48 INFO - 'FP_NO_HOST_CHECK': 'NO',
12:07:48 INFO - 'HOMEDRIVE': 'C:',
12:07:48 INFO - 'HOMEPATH': '\\Users\\cltbld',
12:07:48 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
12:07:48 INFO - 'KTS_VERSION': '1.19c',
12:07:48 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
12:07:48 INFO - 'LOGONSERVER': '\\\\T-W732-IX-114',
12:07:48 INFO - 'MONDIR': 'C:\\Monitor_config\\',
12:07:48 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
12:07:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:07:48 INFO - 'MOZ_NO_REMOTE': '1',
12:07:48 INFO - 'NO_EM_RESTART': '1',
12:07:48 INFO - 'NUMBER_OF_PROCESSORS': '8',
12:07:48 INFO - 'OS': 'Windows_NT',
12:07:48 INFO - 'OURDRIVE': 'C:',
12:07:48 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
12:07:48 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
12:07:48 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
12:07:48 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
12:07:48 INFO - 'PROCESSOR_LEVEL': '6',
12:07:48 INFO - 'PROCESSOR_REVISION': '1e05',
12:07:48 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
12:07:48 INFO - 'PROGRAMFILES': 'C:\\Program Files',
12:07:48 INFO - 'PROMPT': '$P$G',
12:07:48 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
12:07:48 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
12:07:48 INFO - 'PUBLIC': 'C:\\Users\\Public',
12:07:48 INFO - 'PWD': 'C:\\slave\\test',
12:07:48 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
12:07:48 INFO - 'SLAVEDIR': 'C:\\slave\\',
12:07:48 INFO - 'SYSTEMDRIVE': 'C:',
12:07:48 INFO - 'SYSTEMROOT': 'C:\\windows',
12:07:48 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:07:48 INFO - 'TEST1': 'testie',
12:07:48 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:07:48 INFO - 'USERDOMAIN': 'T-W732-IX-114',
12:07:48 INFO - 'USERNAME': 'cltbld',
12:07:48 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
12:07:48 INFO - 'WINDIR': 'C:\\windows',
12:07:48 INFO - 'WINDOWS_TRACING_FLAGS': '3',
12:07:48 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
12:07:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
12:07:48 INFO - Ignoring indexes: https://pypi.python.org/simple/
12:07:48 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
12:07:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kn7hxr-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
12:07:48 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///C:/slave/test/build/tests/mozbase/manifestparser in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 1))
12:07:48 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
12:07:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-aeelps-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
12:07:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///C:/slave/test/build/tests/mozbase/mozcrash in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2))
12:07:48 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
12:07:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-tjfmhx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
12:07:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///C:/slave/test/build/tests/mozbase/mozdebug in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 3))
12:07:48 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
12:07:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-am3scw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
12:07:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///C:/slave/test/build/tests/mozbase/mozdevice in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4))
12:07:49 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
12:07:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-i3ll4a-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
12:07:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///C:/slave/test/build/tests/mozbase/mozfile in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 5))
12:07:49 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
12:07:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vyqyvc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
12:07:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///C:/slave/test/build/tests/mozbase/mozhttpd in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 6))
12:07:49 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
12:07:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ergmed-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
12:07:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///C:/slave/test/build/tests/mozbase/mozinfo in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 7))
12:07:49 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
12:07:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cksncl-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
12:07:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///C:/slave/test/build/tests/mozbase/mozinstall in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 8))
12:07:49 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
12:07:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dld3tm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
12:07:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///C:/slave/test/build/tests/mozbase/mozleak in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 9))
12:07:49 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
12:07:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-x95ru2-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
12:07:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///C:/slave/test/build/tests/mozbase/mozlog in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10))
12:07:49 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
12:07:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ldw9iz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
12:07:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///C:/slave/test/build/tests/mozbase/moznetwork in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 11))
12:07:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
12:07:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-30tqls-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
12:07:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///C:/slave/test/build/tests/mozbase/mozprocess in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 12))
12:07:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
12:07:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fqxwta-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
12:07:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///C:/slave/test/build/tests/mozbase/mozprofile in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 13))
12:07:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
12:07:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-8zaksl-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
12:07:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///C:/slave/test/build/tests/mozbase/mozrunner in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 14))
12:07:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
12:07:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-nyq3f4-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
12:07:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///C:/slave/test/build/tests/mozbase/mozscreenshot in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 15))
12:07:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
12:07:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-blyqnl-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
12:07:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///C:/slave/test/build/tests/mozbase/moztest in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 16))
12:07:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
12:07:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-66uz3l-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
12:07:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///C:/slave/test/build/tests/mozbase/mozversion in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 17))
12:07:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==0.17->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2))
12:07:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==0.17->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2))
12:07:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in c:\slave\test\build\venv\lib\site-packages (from mozdebug==0.1->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 3))
12:07:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.48->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4))
12:07:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.48->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4))
12:07:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10))
12:07:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
12:07:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
12:07:51 INFO - Downloading blessings-1.6.tar.gz
12:07:51 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings
12:07:52 INFO - Installing collected packages: blessings
12:07:52 INFO - Running setup.py install for blessings
12:07:52 INFO - Successfully installed blessings
12:07:52 INFO - Cleaning up...
12:07:55 INFO - Return code: 0
12:07:55 INFO - Done creating virtualenv C:\slave\test\build/venv.
12:07:55 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
12:07:55 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
12:07:55 INFO - Reading from file tmpfile_stdout
12:07:55 INFO - Using _rmtree_windows ...
12:07:55 INFO - Using _rmtree_windows ...
12:07:55 INFO - Current package versions:
12:07:55 INFO - blessings == 1.6
12:07:55 INFO - blobuploader == 1.2.4
12:07:55 INFO - docopt == 0.6.1
12:07:55 INFO - functools32 == 3.2.3-2
12:07:55 INFO - jsonschema == 2.5.1
12:07:55 INFO - manifestparser == 1.1
12:07:55 INFO - mozInstall == 1.12
12:07:55 INFO - mozcrash == 0.17
12:07:55 INFO - mozdebug == 0.1
12:07:55 INFO - mozdevice == 0.48
12:07:55 INFO - mozfile == 1.2
12:07:55 INFO - mozhttpd == 0.7
12:07:55 INFO - mozinfo == 0.9
12:07:55 INFO - mozleak == 0.1
12:07:55 INFO - mozlog == 3.4
12:07:55 INFO - moznetwork == 0.27
12:07:55 INFO - mozprocess == 0.23
12:07:55 INFO - mozprofile == 0.28
12:07:55 INFO - mozrunner == 6.12
12:07:55 INFO - mozscreenshot == 0.1
12:07:55 INFO - mozsystemmonitor == 0.3
12:07:55 INFO - moztest == 0.7
12:07:55 INFO - mozversion == 1.4
12:07:55 INFO - psutil == 3.1.1
12:07:55 INFO - requests == 1.2.3
12:07:55 INFO - Installing None into virtualenv C:\slave\test\build/venv
12:07:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:07:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:07:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:07:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:07:55 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 0x01831640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018D9E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017DB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018F8E90>, '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 0x012FA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019AE520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-114', 'USERDOMAIN': 'T-W732-IX-114', '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-114', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
12:07:55 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\talos\\requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test\build\tests\talos
12:07:55 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 -r C:\slave\test\build\tests\talos\requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub
12:07:55 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
12:07:55 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
12:07:55 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
12:07:55 INFO - 'COMPUTERNAME': 'T-W732-IX-114',
12:07:55 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
12:07:55 INFO - 'CYGWINBASE': 'C:\\cygwin',
12:07:55 INFO - 'DCLOCATION': 'SCL3',
12:07:55 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
12:07:55 INFO - 'FP_NO_HOST_CHECK': 'NO',
12:07:55 INFO - 'HOMEDRIVE': 'C:',
12:07:55 INFO - 'HOMEPATH': '\\Users\\cltbld',
12:07:55 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
12:07:55 INFO - 'KTS_VERSION': '1.19c',
12:07:55 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
12:07:55 INFO - 'LOGONSERVER': '\\\\T-W732-IX-114',
12:07:55 INFO - 'MONDIR': 'C:\\Monitor_config\\',
12:07:55 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
12:07:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:07:55 INFO - 'MOZ_NO_REMOTE': '1',
12:07:55 INFO - 'NO_EM_RESTART': '1',
12:07:55 INFO - 'NUMBER_OF_PROCESSORS': '8',
12:07:55 INFO - 'OS': 'Windows_NT',
12:07:55 INFO - 'OURDRIVE': 'C:',
12:07:55 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
12:07:55 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
12:07:55 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
12:07:55 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
12:07:55 INFO - 'PROCESSOR_LEVEL': '6',
12:07:55 INFO - 'PROCESSOR_REVISION': '1e05',
12:07:55 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
12:07:55 INFO - 'PROGRAMFILES': 'C:\\Program Files',
12:07:55 INFO - 'PROMPT': '$P$G',
12:07:55 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
12:07:55 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
12:07:55 INFO - 'PUBLIC': 'C:\\Users\\Public',
12:07:55 INFO - 'PWD': 'C:\\slave\\test',
12:07:55 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
12:07:55 INFO - 'SLAVEDIR': 'C:\\slave\\',
12:07:55 INFO - 'SYSTEMDRIVE': 'C:',
12:07:55 INFO - 'SYSTEMROOT': 'C:\\windows',
12:07:55 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:07:55 INFO - 'TEST1': 'testie',
12:07:55 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:07:55 INFO - 'USERDOMAIN': 'T-W732-IX-114',
12:07:55 INFO - 'USERNAME': 'cltbld',
12:07:55 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
12:07:55 INFO - 'WINDIR': 'C:\\windows',
12:07:55 INFO - 'WINDOWS_TRACING_FLAGS': '3',
12:07:55 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
12:07:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
12:07:56 INFO - Ignoring indexes: https://pypi.python.org/simple/
12:07:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 1))
12:07:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 2))
12:07:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 3))
12:07:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 4))
12:07:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 5))
12:07:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 6))
12:07:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 7))
12:07:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 8))
12:07:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 9))
12:07:56 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in c:\slave\test\build\venv\lib\site-packages (from mozlog>=3.1->-r C:\slave\test\build\tests\talos\requirements.txt (line 1))
12:07:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test\build\venv\lib\site-packages (from mozhttpd>=0.7->-r C:\slave\test\build\tests\talos\requirements.txt (line 4))
12:07:56 INFO - Cleaning up...
12:07:56 INFO - Return code: 0
12:07:56 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv
12:07:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:07:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:07:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:07:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:07:56 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01831640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018D9E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017DB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018F8E90>, '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 0x012FA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019AE520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-114', 'USERDOMAIN': 'T-W732-IX-114', '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-114', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
12:07:56 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in C:\slave\test\build
12:07:56 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema
12:07:56 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
12:07:56 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
12:07:56 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
12:07:56 INFO - 'COMPUTERNAME': 'T-W732-IX-114',
12:07:56 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
12:07:56 INFO - 'CYGWINBASE': 'C:\\cygwin',
12:07:56 INFO - 'DCLOCATION': 'SCL3',
12:07:56 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
12:07:56 INFO - 'FP_NO_HOST_CHECK': 'NO',
12:07:56 INFO - 'HOMEDRIVE': 'C:',
12:07:56 INFO - 'HOMEPATH': '\\Users\\cltbld',
12:07:56 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
12:07:56 INFO - 'KTS_VERSION': '1.19c',
12:07:56 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
12:07:56 INFO - 'LOGONSERVER': '\\\\T-W732-IX-114',
12:07:56 INFO - 'MONDIR': 'C:\\Monitor_config\\',
12:07:56 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
12:07:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:07:56 INFO - 'MOZ_NO_REMOTE': '1',
12:07:56 INFO - 'NO_EM_RESTART': '1',
12:07:56 INFO - 'NUMBER_OF_PROCESSORS': '8',
12:07:56 INFO - 'OS': 'Windows_NT',
12:07:56 INFO - 'OURDRIVE': 'C:',
12:07:56 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
12:07:56 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
12:07:56 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
12:07:56 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
12:07:56 INFO - 'PROCESSOR_LEVEL': '6',
12:07:56 INFO - 'PROCESSOR_REVISION': '1e05',
12:07:56 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
12:07:56 INFO - 'PROGRAMFILES': 'C:\\Program Files',
12:07:56 INFO - 'PROMPT': '$P$G',
12:07:56 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
12:07:56 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
12:07:56 INFO - 'PUBLIC': 'C:\\Users\\Public',
12:07:56 INFO - 'PWD': 'C:\\slave\\test',
12:07:56 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
12:07:56 INFO - 'SLAVEDIR': 'C:\\slave\\',
12:07:56 INFO - 'SYSTEMDRIVE': 'C:',
12:07:56 INFO - 'SYSTEMROOT': 'C:\\windows',
12:07:56 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:07:56 INFO - 'TEST1': 'testie',
12:07:56 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:07:56 INFO - 'USERDOMAIN': 'T-W732-IX-114',
12:07:56 INFO - 'USERNAME': 'cltbld',
12:07:56 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
12:07:56 INFO - 'WINDIR': 'C:\\windows',
12:07:56 INFO - 'WINDOWS_TRACING_FLAGS': '3',
12:07:56 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
12:07:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
12:07:56 INFO - Ignoring indexes: https://pypi.python.org/simple/
12:07:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages
12:07:56 INFO - Cleaning up...
12:07:56 INFO - Return code: 0
12:07:56 INFO - Running post-action listener: _resource_record_post_action
12:07:56 INFO - Running post-action listener: _start_resource_monitoring
12:07:56 INFO - Starting resource monitoring.
12:07:56 INFO - [mozharness: 2016-10-29 19:07:56.631000Z] Finished create-virtualenv step (success)
12:07:56 INFO - [mozharness: 2016-10-29 19:07:56.631000Z] Running install step.
12:07:56 INFO - Running pre-action listener: _resource_record_pre_action
12:07:56 INFO - Running main action method: install
12:07:56 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
12:07:56 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
12:07:56 INFO - Reading from file tmpfile_stdout
12:07:56 INFO - Using _rmtree_windows ...
12:07:56 INFO - Using _rmtree_windows ...
12:07:56 INFO - Detecting whether we're running mozinstall >=1.0...
12:07:56 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h']
12:07:56 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h
12:07:57 INFO - Reading from file tmpfile_stdout
12:07:57 INFO - Output received:
12:07:57 INFO - Usage: mozinstall-script.py [options] installer
12:07:57 INFO - Options:
12:07:57 INFO - -h, --help show this help message and exit
12:07:57 INFO - -d DEST, --destination=DEST
12:07:57 INFO - Directory to install application into. [default:
12:07:57 INFO - "C:\slave\test"]
12:07:57 INFO - --app=APP Application being installed. [default: firefox]
12:07:57 INFO - Using _rmtree_windows ...
12:07:57 INFO - Using _rmtree_windows ...
12:07:57 INFO - mkdir: C:\slave\test\build\application
12:07:57 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.zip', '--destination', 'C:\\slave\\test\\build\\application']
12:07:57 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py C:\slave\test\build\firefox-52.0a1.en-US.win32.zip --destination C:\slave\test\build\application
12:07:59 INFO - Reading from file tmpfile_stdout
12:07:59 INFO - Output received:
12:07:59 INFO - C:\slave\test\build\application\firefox\firefox.exe
12:07:59 INFO - Using _rmtree_windows ...
12:07:59 INFO - Using _rmtree_windows ...
12:07:59 INFO - Running post-action listener: _resource_record_post_action
12:07:59 INFO - [mozharness: 2016-10-29 19:07:59.217000Z] Finished install step (success)
12:07:59 INFO - [mozharness: 2016-10-29 19:07:59.217000Z] Running run-tests step.
12:07:59 INFO - Running pre-action listener: _resource_record_pre_action
12:07:59 INFO - Running main action method: run_tests
12:07:59 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version']
12:07:59 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version
12:07:59 INFO - Python 2.7.3
12:07:59 INFO - Return code: 0
12:07:59 INFO - grabbing minidump binary from tooltool
12:07: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')]}
12:07:59 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 0x018F8E90>, '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 0x012FA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019AE520>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1
12:07:59 INFO - Running command: ['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'] in C:\slave\test\build
12:07:59 INFO - Copy/paste: c:/mozilla-build/python27/python.exe C:/mozilla-build/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file c:\builds\relengapi.tok fetch -m C:\slave\test\build\tests\config/tooltool-manifests/win32/releng.manifest -o
12:07:59 INFO - Calling ['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'] with output_timeout 600
12:07:59 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'...
12:08:01 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmpphh8zb
12:08:01 INFO - INFO - File integrity verified, renaming tmpphh8zb to win32-minidump_stackwalk.exe
12:08:01 INFO - Return code: 0
12:08:01 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755
12:08:01 INFO - mkdir: C:\slave\test\build\blobber_upload_dir
12:08:01 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir
12:08:01 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe
12:08:01 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir
12:08:01 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/1477758605/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-114', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs_errorsummary.log', '--log-raw=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs_raw.log'] in C:\slave\test\build
12:08:01 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/1477758605/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-114 --webServer localhost --log-tbpl-level=debug --log-errorsummary=C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log --log-raw=C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log
12:08:01 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
12:08:01 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
12:08:01 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
12:08:01 INFO - 'COMPUTERNAME': 'T-W732-IX-114',
12:08:01 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
12:08:01 INFO - 'CYGWINBASE': 'C:\\cygwin',
12:08:01 INFO - 'DCLOCATION': 'SCL3',
12:08:01 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
12:08:01 INFO - 'FP_NO_HOST_CHECK': 'NO',
12:08:01 INFO - 'HOMEDRIVE': 'C:',
12:08:01 INFO - 'HOMEPATH': '\\Users\\cltbld',
12:08:01 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
12:08:01 INFO - 'KTS_VERSION': '1.19c',
12:08:01 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
12:08:01 INFO - 'LOGONSERVER': '\\\\T-W732-IX-114',
12:08:01 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir',
12:08:01 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe',
12:08:01 INFO - 'MONDIR': 'C:\\Monitor_config\\',
12:08:01 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
12:08:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:08:01 INFO - 'MOZ_NO_REMOTE': '1',
12:08:01 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir',
12:08:01 INFO - 'NO_EM_RESTART': '1',
12:08:01 INFO - 'NUMBER_OF_PROCESSORS': '8',
12:08:01 INFO - 'OS': 'Windows_NT',
12:08:01 INFO - 'OURDRIVE': 'C:',
12:08:01 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
12:08:01 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
12:08:01 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
12:08:01 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
12:08:01 INFO - 'PROCESSOR_LEVEL': '6',
12:08:01 INFO - 'PROCESSOR_REVISION': '1e05',
12:08:01 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
12:08:01 INFO - 'PROGRAMFILES': 'C:\\Program Files',
12:08:01 INFO - 'PROMPT': '$P$G',
12:08:01 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
12:08:01 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
12:08:01 INFO - 'PUBLIC': 'C:\\Users\\Public',
12:08:01 INFO - 'PWD': 'C:\\slave\\test',
12:08:01 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos',
12:08:01 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
12:08:01 INFO - 'SLAVEDIR': 'C:\\slave\\',
12:08:01 INFO - 'SYSTEMDRIVE': 'C:',
12:08:01 INFO - 'SYSTEMROOT': 'C:\\windows',
12:08:01 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:08:01 INFO - 'TEST1': 'testie',
12:08:01 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:08:01 INFO - 'USERDOMAIN': 'T-W732-IX-114',
12:08:01 INFO - 'USERNAME': 'cltbld',
12:08:01 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
12:08:01 INFO - 'WINDIR': 'C:\\windows',
12:08:01 INFO - 'WINDOWS_TRACING_FLAGS': '3',
12:08:01 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
12:08:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
12:08:01 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/1477758605/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-114', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs_errorsummary.log', '--log-raw=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs_raw.log'] with output_timeout 3600
12:08:02 INFO - mozversion application_buildid: 20161029093005
12:08:02 INFO - mozversion application_changeset: 969c3295d3aa77931cca26eddb047d9d74bd9858
12:08:02 INFO - mozversion application_display_name: Nightly
12:08:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
12:08:02 INFO - mozversion application_name: Firefox
12:08:02 INFO - mozversion application_remotingname: firefox
12:08:02 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team
12:08:02 INFO - mozversion application_vendor: Mozilla
12:08:02 INFO - mozversion application_version: 52.0a1
12:08:02 INFO - mozversion platform_buildid: 20161029093005
12:08:02 INFO - mozversion platform_changeset: 969c3295d3aa77931cca26eddb047d9d74bd9858
12:08:02 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team
12:08:02 INFO - mozversion platform_version: 52.0a1
12:08:02 INFO - using testdate: 1477768082
12:08:02 INFO - actual date: 1477768082
12:08:02 INFO - starting webserver on 'localhost:49620'
12:08:02 INFO - SUITE-START | Running 2 tests
12:08:02 INFO - TEST-START | dromaeo_css
12:08:02 INFO - operating with platform_type : w7_
12:08:02 INFO - Initialising browser for dromaeo_css test...
12:08:02 INFO - TEST-INFO | started process 1744 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmp3kxmrg\profile http://localhost:49620/getInfo.html)
12:08:10 INFO - PROCESS | 1744 | __metrics Screen width/height:1600/1200
12:08:10 INFO - PROCESS | 1744 | colorDepth:24
12:08:10 INFO - PROCESS | 1744 | Browser inner width/height: 1010/674
12:08:10 INFO - PROCESS | 1744 | __metrics
12:08:11 INFO - PROCESS | 1744 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows"
12:08:11 INFO - PROCESS | 1744 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 205: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
12:08:12 INFO - TEST-INFO | 1744: exit 0
12:08:12 INFO - Browser initialized.
12:08:12 INFO - Running cycle 1/1 for dromaeo_css test...
12:08:12 INFO - TEST-INFO | started process 1164 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmp3kxmrg\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1)
12:08:13 INFO - PROCESS | 1164 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0
12:08:24 INFO - PROCESS | 1164 | RSS: Main: 124608512
12:08:24 INFO - PROCESS | 1164 |
12:09:15 INFO - PROCESS | 1164 | 1477768155140 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no]
12:11:25 INFO - PROCESS | 1164 | Cycle 1(1): loaded http://localhost:49620/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49620/tests/dromaeo/cssquery-ext.html)
12:11:28 INFO - PROCESS | 1164 | RSS: Main: 317272064
12:11:28 INFO - PROCESS | 1164 |
12:14:30 INFO - PROCESS | 1164 | Cycle 1(1): loaded http://localhost:49620/tests/dromaeo/cssquery-ext.html (next: http://localhost:49620/tests/dromaeo/cssquery-jquery.html)
12:14:31 INFO - PROCESS | 1164 | RSS: Main: 198107136
12:14:31 INFO - PROCESS | 1164 |
12:17:33 INFO - PROCESS | 1164 | Cycle 1(1): loaded http://localhost:49620/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49620/tests/dromaeo/cssquery-mootools.html)
12:17:33 INFO - PROCESS | 1164 | RSS: Main: 161767424
12:17:33 INFO - PROCESS | 1164 |
12:20:35 INFO - PROCESS | 1164 | Cycle 1(1): loaded http://localhost:49620/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49620/tests/dromaeo/cssquery-prototype.html)
12:20:36 INFO - PROCESS | 1164 | RSS: Main: 143405056
12:20:36 INFO - PROCESS | 1164 |
12:23:39 INFO - PROCESS | 1164 | Cycle 1(1): loaded http://localhost:49620/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49620/tests/dromaeo/cssquery-yui.html)
12:23:39 INFO - PROCESS | 1164 | RSS: Main: 134803456
12:23:39 INFO - PROCESS | 1164 |
12:26:41 INFO - PROCESS | 1164 | Cycle 1(1): loaded http://localhost:49620/tests/dromaeo/cssquery-yui.html (next: http://localhost:49620/tests/dromaeo/cssquery-dojo.html)
12:26:42 INFO - PROCESS | 1164 | RSS: Main: 187396096
12:26:42 INFO - PROCESS | 1164 |
12:26:42 INFO - PROCESS | 1164 | __start_tp_report
12:26:42 INFO - PROCESS | 1164 | _x_x_mozilla_page_load
12:26:42 INFO - PROCESS | 1164 | _x_x_mozilla_page_load_details
12:26:42 INFO - PROCESS | 1164 | |i|pagename|runs|
12:26:42 INFO - PROCESS | 1164 | |0;dojo.html;2663.6004599310104;2676.5182267191904;2699.109293933001;2730.7951903607236;2736.698963114058;4597.034622729231;4603.769811509428;4612.953870461294;4699.718016918987;4702.976485117574;4582.3813785139;4613.6539759518;4637.628989680826;4712.740799256039;4729.408823897013;4052.9594704053025;4056.7565946043183;4060.4924384451942;4075.4294398784195;4092.6725861930972;4006.4991876015497;4047.7773722445254;4061;4084.6732261419015;4087.6321131098193;4072.36878283865;4089.284375234337;4092.386142078682;4115.403266526352;4132.2768515509815;4042.5300645263374;4060.695447841409;4075.9796201018803;4098.426220329156;4171.728837625575;3986.382110772835;4016.096378314856;4116.5060192776755;4118.5263694675195;4125.814338354781;4569.862904112882;4588.954110458886;4605.240135377692;4655.976720116411;4660.72035677857;4813.855584332476;4862.586680132193;4880.902381952376;4974.77613507393;4979.327790748275;4897.975510122426;4907.9263811042865;5006.349174607314;5017.54842064216;5020.146575082244;4415.8233670653135;4513.54864513546;4559.475660299075;4569.428821397359;4572.9314060289125;4921.188003979375;4930.137225985438;5011.899762004739;5023.020511000393;5083.923741143886;4813.085513752376;4888.120138375056;4912.533309335608;4918.163912134874;4922.114019476457;4731.881702957454;4896.804127834846;4991.775370108354;5000.449950505442;5001.974990125026;4794.7842347093765;4808.735519546459;4858.392700912386;4896.510348965075;4901.583365413909;4880.365552478155;4880.950595621958;4926.852194434173;4942.802287908516;4945.505449455098;3282.7702060855513;3300.8679652813616;3301.669833016679;3371.4605663094276;3377.2907689385015;2989.790714649997;3034.1959380764115;3043.1327071784876;3084.0593618946436;3091.644460887027;4589.609883159902;4598.356230127785;4624.653151013687;4627.629789616823;4658.58072773452;4808.927866082012;4899.1426500362295;5003.699778013331;5004.549590536796;5028.823991160292;4517.141743068806;4545.318202269686;4564;4671.369365135663;4673.883152921204;4049.5747946465785;4113.71204015722;4150;4161.042960119129;4162.542120366757;5071.315372424676;5093.083245015932;5169.560587350042;5185.740712964262;5187.610929180251;4920.901581968269;4938.827141050046;4964.900701985868;4996.400431948189;5000.349954505891;5114.4374118847645;5149.845504634867;5170;5179.093658609653;5187.377514698261;4759.5240475953515;4760.690555113906;4821.807127714852;4830.516948305141;4832.081904438145;4763.666543342071;4890;4896.485868983706;4905.5094490550655;4906.852794416173;4734.905301893874;4750;4778.689385189951;4837.637177211794;4873.049755297825;4633.328167415773;4724.929126063043;4802.255650374198;4856.8300109495995;4864.416270047475;4573.634109271184;4580.1984652686315;4645.3032045193495;4677.742724150068;4695.906081878412;4780.569748722632;4783.760811959319;4784.306275590089;4784.521547845187;4786.114568804782;4724.220503617003;4724.362211101457;4725.267583524559;4727.3381726559;4732.148213321635
12:26:42 INFO - PROCESS | 1164 | |1;ext.html;531.1209947536826;531.3650188025312;538.3378444513247;542.8751387180948;546.8906218756247;34928;35665;35923;36342.454863177074;37633;35899.64100358995;36298;36490.270194596094;36684;37155.25689486209;6197.225346831646;7512.849743005137;7563.546187228771;7679.500832445873;7679.769606911802;6190.69046547673;9246.12161844627;9311.068893110703;9369.15677589017;9689.806203875918;9436.150746432819;9573.904260957406;9615.759606009835;9642.939276679559;9655.32412731109;9464.1009104135;9485;9491.288153388488;9509;9510.714678559654;6393.7442502299855;6550.017497375384;8577.656893724243;8842.46945183291;8881.73354799357;33635;33863.83068084669;33875.32249355024;34676.30647387063;34747;2592.325995241232;2636.5649667804787;2695.1644990053146;2698.595210718408;2705.851178185199;26958.865205673847;26986.5157416342;27170;27251.318717032034;27440.45119097607;3274.099622603779;3296.6373698893103;3312.7411583598077;3373.2578832656777;3452.4130897747445;324732;325671;325857;326310;326945.3652731745;47210.29184562234;47564.28653570199;47568;47582.28626570604;47686.28470572944;26369.20892373232;27019.1894243173;49809.75095124574;49909.25136122961;49936.75031624819;13155.342232888317;13202.273874936778;13414.396352163982;13511.932440337736;13545.187288762705;8318.04342500608;10303.330283531546;10399.84400233997;10401.63594274212;10412.635557755442;11095.782223155356;15322.463713770188;15651.37394504207;15652;15866.52400427989;5986.191864098378;5998.670073146019;6099.96950015256;6114.357992410822;6190.535709821781;635.8029011006511;669.3774789445862;670.5339788846704;723.8552289542113;761.7295859969631;24104.15635452786;24404.877975610365;25000.624990625154;25125.87437062803;25566;814.5357146426599;816.9502189686225;832.3382910586099;855.922966932979;878.1789027259405;12749.322642245232;12935;13144.802827957588;13282.3358832058;13327.533536326182;473158;475577;476274;477122;484149;44580.55419445829;45100.548994509634;45110;45215.77392113018;45225.547744522795;859.0850743957665;875.2297977779641;1026.1534234256767;1036.657902892044;1048.8584041154347;24100.276991690276;24131.276061718178;24651.63022554699;24931.376715582257;25444;36538.63461365458;38565;38581.807090964365;38708.032299192746;39921.60078399179;371448;373977;374862;375167;376324;20954;21308.893455532623;21608.567828643652;22308.77691223067;22326.441838954157;33606.32787344191;33654.6180733887;33666.98999030033;33667.32665346728;33880.83059584686;32334.83832580822;33325.50011749874;33649.66350336465;33779.493307599914;35262.294754105285;33263;33483;33821.15447113842;33834.323313533096;33919.25130481784;31273.687263127988;31693.683063169996;31792.68207317897;31927;32085.518717218794;28117.71882281151;28461.857690711415;28468.430631386844;28611.856940715163;28986;22584.661230081234;23494.530109397376;23667.171648992204;23711.17010904679;23723.631003366714
12:26:42 INFO - PROCESS | 1164 | |2;jquery.html;271.66178108255224;297.28502950404277;302.75930635145056;303.675067677585;309.35036423510616;12371.13402061856;13117.54088606899;16397.590060248498;16712.100635414692;17215.483535493957;15761.448349307777;15919.920400397981;15996.12021338826;16196.83803161968;16199.757003644954;5056.620753443488;5072.289879416885;5094.4905509448945;5173.818916337929;5243.10867152585;4985.601151907838;5000.5999520038495;5012.172991456406;5057.772400241998;5086.440491545927;5011.32347133136;5065.366829146356;5100.719460429675;5142.305788718512;5145.948540514603;4843.4914333994775;5071.8985620287385;5126.871828204306;5132.101882170624;5137.871553211162;4365.476142862845;4370.125974805052;4377.5403582623685;4378.1462614790125;4422.491413487426;20427.080781364726;20555.280565180146;20574.58850822975;21075.156993720233;21209;1809.7556829827936;1984.8412127029803;1986.9503262418411;2037.4193354893932;2071.2129390831537;13373.465061397532;13410.530631427853;13547.661308467268;13713.314334283346;13837.654058648513;1637.451453762991;1644.8519633233066;1655.7019736447435;1665.1757380096929;1673.6067024249292;77536;81330;83119.16880831236;83615;83694.58152709258;8391.706290279812;8430.915690843012;8566.657333706582;8786.297096232414;8862.379633425599;11339.376334301693;11344.149188810872;11352.48913798881;11363.943180284046;11375.772484550429;6874.381305682413;6897.9655101725175;6911.205211400753;7008.649567521604;7079.92920070803;7000.824979375455;7059.435245180476;7126.893096603555;7159.964200178966;7229.674664640103;5405.378381486179;5418.972905135528;5439.809606663748;5453.672779633234;5551.7779288828;1466.1789397937189;1498.655309278872;1509.3208056374456;1513.954581362561;1563.1324614838873;753.7135888362496;767.6583920155477;782.4327362662139;790.905091389037;866.6316815353449;17086.658266834595;17216;17341.219645116;17536.649267014844;17954.461366159037;791.2127433203999;802.2939812964554;820.6676296100089;828.9502629842228;836.8493671139254;9711.611535538641;9802.852957205647;9962.352447090916;9965.152961998167;10156;382739;387957;391838;405057;405157;83064.16935830565;84960.57519712485;85578.14421855826;86097;86748;794.940379471542;830.3689196210803;831.8003679116969;832.4089896173784;833.5707110837923;15387.76918346248;15524.456644017406;15697.60755981064;15815.920920395325;16326;41189;42848.78575607227;43138.35292470553;44825.77587112044;45146;56781.71609141928;58140;58579;58736.706316468146;58987.70506147613;6948.965255173692;7200.243974382614;7262.564246145248;7281.381082608038;7319.268073192638;9600.66397676078;12632.43154058088;12659;12715.237085774883;12751.426185821474;10423.530941107516;10609.787804243717;10626.203034772578;10649.787004259717;10795.974382433657;10107.79784404293;10152.340097893613;10328.122109620766;10390.116840068678;10595.940405959342;10385.428801415994;10708.357498550113;10787.460626968777;10860.674179774303;11072.667819965414;7080.946525507545;7206;7216.242294559206;7256.766349720455;7307;8949.59726812282;10206.948965255126;10277.897221027693;10458;10700.625478108228
12:26:42 INFO - PROCESS | 1164 | |3;mootools.html;917.6512925088465;1069.240839004307;1073.5330131392843;1074.0762943868274;1208.6374087773672;5851.268591426072;6449.709763060668;6631.204255489336;6679.866402671943;6694.832629184273;6715.697793599287;6718.361755633219;6738.966305168479;6764.255931847493;6789;1847.3626598823453;1852.5461261990831;1854.9721754173631;1873.288150502807;1876.3714155757837;1791.7133258678614;1823.2798044772308;1832.2762508809012;1865.81341865813;1867.4771064102074;1814.9836091788575;1824.3797108982942;1824.8357647811695;1848.066726303215;1852.3424184414541;1772.4062439082923;1775.618242077953;1780.563761878335;1780.9020503872282;1806.9006204658738;1776.0320625259264;1801.5586181385513;1802.4772815883377;1804.3504338438152;1819.2268285978407;6181.536384771161;6255.718492667841;6289.213848268966;6378.904316435257;6497.967510162419;4009.258287216874;4080.3471444568727;4105.384192371138;4109.157622687342;4109.8972525686795;2703.7296270372804;2797.650293713286;2830.80184387093;2832.3485598312295;2834.957475637867;803.8271771569082;834.6369329341695;838.6896848166217;856.9957150214271;862.8446879561676;8155.592220389016;8205.507669539847;8280.668773249063;8305.46014509055;8316.667333306661;3348.8995330140133;3390.5083762854742;3429.639887811794;3443.6212016678146;3488.145404375944;6567.835804104936;6683.365080317362;6694.665266736741;6696.330366963265;6700.49746269022;4008.997750562359;4010.057636455431;4073.348264277701;4083.3670781028986;4094.63148316647;3343.6823501767294;3407.7784943978563;3788.1855401088897;3843.461915331856;3854.4989151410136;2555.5016771729333;2645.576707726793;2793.0782841662203;2877.6259086318646;2880.740733333968;877.2806298834894;884.6594061286451;887.1704955866286;891.41612243979;902.3412908576645;565.4345654345655;583.8073435766187;678.4301187002941;679.8368391586083;725.5719125715852;4098.098418347959;4114.958850411517;4262.360645903077;4301.07526881722;4325.091730736518;659.4295934017041;687.1547995964991;691.2810676896072;713.3651050565002;746.6714645555938;3002.489576771954;3002.804817686887;3013.74383177428;3017.683143269969;3049.5883055787626;7997.160298168725;8025.197480251928;8052.396070294634;8119.594020298961;8252.21603947624;8075;8083.19168083187;8248.381371397169;8272.503649781032;8277.58612069394;6067.059605761114;6085.817425477331;6140;6229.937700623026;6274.121622972787;4267.103908179317;4279.978600107104;4362.716423432466;4402.207602631557;4509.932351014671;7623.16145224036;7631.351335136354;7631.771046868603;7632.19861914491;7635.618219089134;7315.268473152642;7517.361024312994;7603.429742769204;7681.039870016248;7743.457957943116;5796.797112101056;5824;5832.562557808096;5898;5973.432523910222;5802.4197580241635;6023.186869772525;6174.722137503733;6350;6362.363763623601;5743.0811070228565;5750.30996280449;5910.70446477683;5963.70181490915;5997.310309314485;5619.578531610227;5622.943770562242;5749.597528172988;5759.683217423082;5785.710714464344;5081.00920320534;5168.922466162935;5328.7602057906715;5433;5448.264484294728;3759.379702349082;3792.886213413602;3803.5625903021505;3822.7324087313623;3911.100446897173;3796.2976849282973;3805.619438056283;3855.383138697795;3882.378819388884;3915.804209789442
12:26:42 INFO - PROCESS | 1164 | |4;prototype.html;247.98388104773198;255.46480124139927;258.83693273237844;259.29213247833417;260.847404268503;4856.223004319309;5002.324686167375;5043.243513472977;5071.771770270338;5088.135017047106;4808.7114773113635;4974.875628109299;4979.979104283622;4988;4992.825251116212;3598.1364472526534;3604;3679.6504332088407;3690.372636651769;3692.4461330800327;3523.0839981604713;3536.664016918389;3602.7117830573493;3661.9816900915457;3688.151725103228;3535.7348198885056;3568.910777230577;3577.62434944331;3619.040954147153;3657.5062366580437;3630.2376500934834;3651.7626354287017;3659.3779057560278;3659.6157403472785;3660.7919386602357;3104.425681248976;3117.937641247185;3157;3160.4311223979676;3165.7942233754734;4789.87437952081;4799.160146974265;4841.225403935388;4894.119058569456;4909.558139767402;4043.1913617276664;4221.24017676818;4244.427002354704;4252.512280316018;4277.679174061957;5067.366579177603;5084.830488987516;5128.25640282157;5166;5179.8446046618665;3487.9187451889993;3496.4230901901155;3498.4577390504514;3498.597661268961;3501.8774342897877;5318.680879147263;5406.297181366397;5429.375621803464;5433.945660543383;5440.619156659035;3288.769786114997;3454.844531996016;3598.982005089958;3598.9820050900103;3599.280143971216;3500.8774692886136;3501.982490087584;3511.8244087795506;3513;3515.525404070469;4117.75293482392;4168.166366726667;4174.540800511942;4178.937315940263;4179.6029377209115;5242.449542798027;5242.7378631068295;5260.079486089919;5277.050130976384;5279.52484276417;1161.6689243565543;1202.1765090912752;1204.5723768062212;1209.7943349630584;1212.745323482061;749.8725216713062;770.3721467004374;778.7469072551444;785.6739453127003;792.0970094092739;4830.961343311134;4894.583960363338;4898.3142360068905;4915.238138088602;4918.065567542227;5296.1463487778965;5303.549198318186;5304.38999515053;5307.416184219733;5320.574354051667;4819.397575303087;4869.099216644932;4880.243562247786;4907.582855457326;4917.5082491751255;2744.766694830922;2818.26725051484;2828.420173864353;2849.187981425284;2853.757430618379;5549.778008879681;5551.639143455663;5558.999380111538;5559.861003474864;5589.413111623302;5386.245925570423;5413.702246376487;5413.891722165535;5417.620766546383;5422.75597598109;5390.19147127934;5547.72261386929;5566.777328906879;5657.2928383952;5668.7165641718575;5409.729513524387;5424.294841670558;5424.349078110652;5440.6735595864375;5453.400125986059;5341.65279256847;5342.973285133549;5358.544523715372;5368.3557973041925;5370.758315875873;5446.537044351275;5650.745716442688;5652.689102099424;5658.575606829587;5668.178114173339;3482.791032538056;3493.650634936486;3503.649635036578;3506.947395789014;3512.8243587819998;5307.7611507483025;5395.949539175088;5408.810691625774;5410.404855465974;5428.96849598575;5255.684658920477;5255.737213139404;5260.9736951315;5270.552003079628;5276.103062479465;5019.297298378303;5222.843314700565;5258.842234732964;5263.289455923401;5285.312909321918;5076.213186956028;5120.385553733576;5140.43455219933;5149.510796474329;5161.754636732883;5052.292679025013;5186.818461353834;5218.390804597701;5247.947520524746;5262.079136151082;5047.0316576535915;5112.463191364853;5147.433782284021;5173.611979101507;5189.922151167811
12:26:42 INFO - PROCESS | 1164 | |5;yui.html;522.6210997027154;545.0570513012489;590.7016956436998;599.5293694449857;599.7870755881663;12683.809742853853;13317.26755028473;13703.862961370383;13958.464568897425;14011.579652610439;10400.583976640944;11724;11749.118816088785;12310.261384316947;12368.690782730437;535.3575709149018;539.0701040704793;539.7948779463799;539.8542393553738;543.1038786003087;527.5963887625969;544.1266766839226;544.4093158922574;544.4691425859795;544.6813614035774;572.378968818832;572.5534083414943;573.8766165274466;574.187524652616;574.2706762411734;567.8495198772329;568.9857753556153;570.0451743329927;570.5635189080336;571.1090698510337;413.75381647919437;414.09933394866044;414.6413352450139;414.7407870081199;415.7879481464475;10931.836022459669;11032.50353734084;11043.950824671723;11053.889461105447;11064.778704425866;10460.947695261551;10527.263091583593;10546.103581195608;10701.250912436133;10776.19178561611;9101.31740119487;9239.076092390775;9298.814023719488;9497.097775711365;9642.903570964341;3178.8569514371907;3214.4856822908223;3262.1192278084786;3270.9182270443193;3357.345317663057;59669;59693;60139;61078.69460652668;61428.69285653543;7992.801079837955;8046.678132874619;8113.350931925432;8158.4289099762445;8245.95877020611;13112.147710398793;13299.401526931311;13420.463181472827;13447.193168409927;13452.46190152383;6553.770618028346;6579.374959378852;6632.744330265739;6788.083608712859;6831.6242606657115;3765.642263984917;3786.7159963002887;3818.236352729465;3856.6914646828186;3954.5452272988973;2292.3925159832656;2328.4993726348916;2344.5310937812505;2345.7185137783235;2352.8588284702973;469.31480039142554;469.8073789746256;470.3062982101373;477.02686519500526;482.4138671514127;545.5362941499693;545.8826352334188;562.5359078759958;575.671867035794;583.7285662167112;7864.21357864209;8059.476134051385;8104.675812967534;8149.877751833727;8194;550.9366422861342;562.8930503204379;572.1132245020202;580.5964854426218;602.6835911146683;3865.420186971977;3960.6237407446247;4033.5764744701382;4049.6152865477734;4071.226466971266;73099.63450182715;75175.62412188013;77174;77716.61141694369;77824.61087694525;55281;67132.664336678;69294;69875.3012469879;70942.64528677323;55446.99999999919;55761;59399.70300148472;61060;63910;7784.649690764042;8072.273495385443;8188;8342.83314333722;8629.09394513592;39185.8040709806;40743.7962810184;41821.79089104535;41854.162916742105;41914.1617167661;52300.47699522956;56123.43876561346;56183.71908140433;56821;57033;10724.51739671701;10948.45257737126;10969.451527423438;11098.2786118902;11117.11063114965;12528.937355313165;12803.231806091664;12872.420741066862;13089.803652945404;13106.344682765633;11661.591844285409;11950.880491194977;12063.396830158632;12117.27296362221;12245.510179593073;12113.939430302793;12192.695182620506;12433.440495177558;12477.251364918135;12869.099163058496;11711.004564612103;11830;11978.005498625344;12088.939555302168;12153.513859445524;10419.583216671248;10460.63387781424;10700.518476668412;10789.838152427878;10871.130309575057;10325;10414.687559373231;10502;10521.316114452537;10537.736556586147
12:26:42 INFO - PROCESS | 1164 | __end_tp_report
12:26:42 INFO - PROCESS | 1164 | __start_cc_report
12:26:42 INFO - PROCESS | 1164 | _x_x_mozilla_cycle_collect,2825
12:26:42 INFO - PROCESS | 1164 | __end_cc_report
12:26:42 INFO - PROCESS | 1164 | __startTimestamp1477769202202__endTimestamp
12:26:42 INFO - PROCESS | 1164 |
12:26:42 INFO - PROCESS | 1164 | ------- Summary: start -------
12:26:42 INFO - PROCESS | 1164 | Number of tests: 6
12:26:42 INFO - PROCESS | 1164 |
12:26:42 INFO - PROCESS | 1164 | [#0] dojo.html Cycles:165 Average:4524.26 Median:4724.93 stddev:578.01 (12.2%) stddev-sans-first:561.05
12:26:42 INFO - PROCESS | 1164 | Values: 2663.6 2676.5 2699.1 2730.8 2736.7 4597.0 4603.8 4613.0 4699.7 4703.0 4582.4 4613.7 4637.6 4712.7 4729.4 4053.0 4056.8 4060.5 4075.4 4092.7 4006.5 4047.8 4061.0 4084.7 4087.6 4072.4 4089.3 4092.4 4115.4 4132.3 4042.5 4060.7 4076.0 4098.4 4171.7 3986.4 4016.1 4116.5 4118.5 4125.8 4569.9 4589.0 4605.2 4656.0 4660.7 4813.9 4862.6 4880.9 4974.8 4979.3 4898.0 4907.9 5006.3 5017.5 5020.1 4415.8 4513.5 4559.5 4569.4 4572.9 4921.2 4930.1 5011.9 5023.0 5083.9 4813.1 4888.1 4912.5 4918.2 4922.1 4731.9 4896.8 4991.8 5000.4 5002.0 4794.8 4808.7 4858.4 4896.5 4901.6 4880.4 4881.0 4926.9 4942.8 4945.5 3282.8 3300.9 3301.7 3371.5 3377.3 2989.8 3034.2 3043.1 3084.1 3091.6 4589.6 4598.4 4624.7 4627.6 4658.6 4808.9 4899.1 5003.7 5004.5 5028.8 4517.1 4545.3 4564.0 4671.4 4673.9 4049.6 4113.7 4150.0 4161.0 4162.5 5071.3 5093.1 5169.6 5185.7 5187.6 4920.9 4938.8 4964.9 4996.4 5000.3 5114.4 5149.8 5170.0 5179.1 5187.4 4759.5 4760.7 4821.8 4830.5 4832.1 4763.7 4890.0 4896.5 4905.5 4906.9 4734.9 4750.0 4778.7 4837.6 4873.0 4633.3 4724.9 4802.3 4856.8 4864.4 4573.6 4580.2 4645.3 4677.7 4695.9 4780.6 4783.8 4784.3 4784.5 4786.1 4724.2 4724.4 4725.3 4727.3 4732.1
12:26:42 INFO - PROCESS | 1164 |
12:26:42 INFO - PROCESS | 1164 | [#1] ext.html Cycles:180 Average:51341.66 Median:19064.36 stddev:105692.03 (554.4%) stddev-sans-first:105919.68
12:26:42 INFO - PROCESS | 1164 | Values: 531.1 531.4 538.3 542.9 546.9 34928.0 35665.0 35923.0 36342.5 37633.0 35899.6 36298.0 36490.3 36684.0 37155.3 6197.2 7512.8 7563.5 7679.5 7679.8 6190.7 9246.1 9311.1 9369.2 9689.8 9436.2 9573.9 9615.8 9642.9 9655.3 9464.1 9485.0 9491.3 9509.0 9510.7 6393.7 6550.0 8577.7 8842.5 8881.7 33635.0 33863.8 33875.3 34676.3 34747.0 2592.3 2636.6 2695.2 2698.6 2705.9 26958.9 26986.5 27170.0 27251.3 27440.5 3274.1 3296.6 3312.7 3373.3 3452.4 324732.0 325671.0 325857.0 326310.0 326945.4 47210.3 47564.3 47568.0 47582.3 47686.3 26369.2 27019.2 49809.8 49909.3 49936.8 13155.3 13202.3 13414.4 13511.9 13545.2 8318.0 10303.3 10399.8 10401.6 10412.6 11095.8 15322.5 15651.4 15652.0 15866.5 5986.2 5998.7 6100.0 6114.4 6190.5 635.8 669.4 670.5 723.9 761.7 24104.2 24404.9 25000.6 25125.9 25566.0 814.5 817.0 832.3 855.9 878.2 12749.3 12935.0 13144.8 13282.3 13327.5 473158.0 475577.0 476274.0 477122.0 484149.0 44580.6 45100.5 45110.0 45215.8 45225.5 859.1 875.2 1026.2 1036.7 1048.9 24100.3 24131.3 24651.6 24931.4 25444.0 36538.6 38565.0 38581.8 38708.0 39921.6 371448.0 373977.0 374862.0 375167.0 376324.0 20954.0 21308.9 21608.6 22308.8 22326.4 33606.3 33654.6 33667.0 33667.3 33880.8 32334.8 33325.5 33649.7 33779.5 35262.3 33263.0 33483.0 33821.2 33834.3 33919.3 31273.7 31693.7 31792.7 31927.0 32085.5 28117.7 28461.9 28468.4 28611.9 28986.0 22584.7 23494.5 23667.2 23711.2 23723.6
12:26:42 INFO - PROCESS | 1164 |
12:26:42 INFO - PROCESS | 1164 | [#2] jquery.html Cycles:180 Average:25343.11 Median:4400.32 stddev:65758.27 (1494.4%) stddev-sans-first:65915.79
12:26:42 INFO - PROCESS | 1164 | Values: 271.7 297.3 302.8 303.7 309.4 12371.1 13117.5 16397.6 16712.1 17215.5 15761.4 15919.9 15996.1 16196.8 16199.8 5056.6 5072.3 5094.5 5173.8 5243.1 4985.6 5000.6 5012.2 5057.8 5086.4 5011.3 5065.4 5100.7 5142.3 5145.9 4843.5 5071.9 5126.9 5132.1 5137.9 4365.5 4370.1 4377.5 4378.1 4422.5 20427.1 20555.3 20574.6 21075.2 21209.0 1809.8 1984.8 1987.0 2037.4 2071.2 13373.5 13410.5 13547.7 13713.3 13837.7 1637.5 1644.9 1655.7 1665.2 1673.6 77536.0 81330.0 83119.2 83615.0 83694.6 8391.7 8430.9 8566.7 8786.3 8862.4 11339.4 11344.1 11352.5 11363.9 11375.8 6874.4 6898.0 6911.2 7008.6 7079.9 7000.8 7059.4 7126.9 7160.0 7229.7 5405.4 5419.0 5439.8 5453.7 5551.8 1466.2 1498.7 1509.3 1514.0 1563.1 753.7 767.7 782.4 790.9 866.6 17086.7 17216.0 17341.2 17536.6 17954.5 791.2 802.3 820.7 829.0 836.8 9711.6 9802.9 9962.4 9965.2 10156.0 382739.0 387957.0 391838.0 405057.0 405157.0 83064.2 84960.6 85578.1 86097.0 86748.0 794.9 830.4 831.8 832.4 833.6 15387.8 15524.5 15697.6 15815.9 16326.0 41189.0 42848.8 43138.4 44825.8 45146.0 56781.7 58140.0 58579.0 58736.7 58987.7 6949.0 7200.2 7262.6 7281.4 7319.3 9600.7 12632.4 12659.0 12715.2 12751.4 10423.5 10609.8 10626.2 10649.8 10796.0 10107.8 10152.3 10328.1 10390.1 10595.9 10385.4 10708.4 10787.5 10860.7 11072.7 7080.9 7206.0 7216.2 7256.8 7307.0 8949.6 10206.9 10277.9 10458.0 10700.6
12:26:42 INFO - PROCESS | 1164 |
12:26:42 INFO - PROCESS | 1164 | [#3] mootools.html Cycles:180 Average:4284.84 Median:3094.19 stddev:2372.25 (76.7%) stddev-sans-first:2365.40
12:26:42 INFO - PROCESS | 1164 | Values: 917.7 1069.2 1073.5 1074.1 1208.6 5851.3 6449.7 6631.2 6679.9 6694.8 6715.7 6718.4 6739.0 6764.3 6789.0 1847.4 1852.5 1855.0 1873.3 1876.4 1791.7 1823.3 1832.3 1865.8 1867.5 1815.0 1824.4 1824.8 1848.1 1852.3 1772.4 1775.6 1780.6 1780.9 1806.9 1776.0 1801.6 1802.5 1804.4 1819.2 6181.5 6255.7 6289.2 6378.9 6498.0 4009.3 4080.3 4105.4 4109.2 4109.9 2703.7 2797.7 2830.8 2832.3 2835.0 803.8 834.6 838.7 857.0 862.8 8155.6 8205.5 8280.7 8305.5 8316.7 3348.9 3390.5 3429.6 3443.6 3488.1 6567.8 6683.4 6694.7 6696.3 6700.5 4009.0 4010.1 4073.3 4083.4 4094.6 3343.7 3407.8 3788.2 3843.5 3854.5 2555.5 2645.6 2793.1 2877.6 2880.7 877.3 884.7 887.2 891.4 902.3 565.4 583.8 678.4 679.8 725.6 4098.1 4115.0 4262.4 4301.1 4325.1 659.4 687.2 691.3 713.4 746.7 3002.5 3002.8 3013.7 3017.7 3049.6 7997.2 8025.2 8052.4 8119.6 8252.2 8075.0 8083.2 8248.4 8272.5 8277.6 6067.1 6085.8 6140.0 6229.9 6274.1 4267.1 4280.0 4362.7 4402.2 4509.9 7623.2 7631.4 7631.8 7632.2 7635.6 7315.3 7517.4 7603.4 7681.0 7743.5 5796.8 5824.0 5832.6 5898.0 5973.4 5802.4 6023.2 6174.7 6350.0 6362.4 5743.1 5750.3 5910.7 5963.7 5997.3 5619.6 5622.9 5749.6 5759.7 5785.7 5081.0 5168.9 5328.8 5433.0 5448.3 3759.4 3792.9 3803.6 3822.7 3911.1 3796.3 3805.6 3855.4 3882.4 3915.8
12:26:42 INFO - PROCESS | 1164 |
12:26:42 INFO - PROCESS | 1164 | [#4] prototype.html Cycles:180 Average:4305.92 Median:4997.57 stddev:1356.22 (27.1%) stddev-sans-first:1325.38
12:26:42 INFO - PROCESS | 1164 | Values: 248.0 255.5 258.8 259.3 260.8 4856.2 5002.3 5043.2 5071.8 5088.1 4808.7 4974.9 4980.0 4988.0 4992.8 3598.1 3604.0 3679.7 3690.4 3692.4 3523.1 3536.7 3602.7 3662.0 3688.2 3535.7 3568.9 3577.6 3619.0 3657.5 3630.2 3651.8 3659.4 3659.6 3660.8 3104.4 3117.9 3157.0 3160.4 3165.8 4789.9 4799.2 4841.2 4894.1 4909.6 4043.2 4221.2 4244.4 4252.5 4277.7 5067.4 5084.8 5128.3 5166.0 5179.8 3487.9 3496.4 3498.5 3498.6 3501.9 5318.7 5406.3 5429.4 5433.9 5440.6 3288.8 3454.8 3599.0 3599.0 3599.3 3500.9 3502.0 3511.8 3513.0 3515.5 4117.8 4168.2 4174.5 4178.9 4179.6 5242.4 5242.7 5260.1 5277.1 5279.5 1161.7 1202.2 1204.6 1209.8 1212.7 749.9 770.4 778.7 785.7 792.1 4831.0 4894.6 4898.3 4915.2 4918.1 5296.1 5303.5 5304.4 5307.4 5320.6 4819.4 4869.1 4880.2 4907.6 4917.5 2744.8 2818.3 2828.4 2849.2 2853.8 5549.8 5551.6 5559.0 5559.9 5589.4 5386.2 5413.7 5413.9 5417.6 5422.8 5390.2 5547.7 5566.8 5657.3 5668.7 5409.7 5424.3 5424.3 5440.7 5453.4 5341.7 5343.0 5358.5 5368.4 5370.8 5446.5 5650.7 5652.7 5658.6 5668.2 3482.8 3493.7 3503.6 3506.9 3512.8 5307.8 5395.9 5408.8 5410.4 5429.0 5255.7 5255.7 5261.0 5270.6 5276.1 5019.3 5222.8 5258.8 5263.3 5285.3 5076.2 5120.4 5140.4 5149.5 5161.8 5052.3 5186.8 5218.4 5247.9 5262.1 5047.0 5112.5 5147.4 5173.6 5189.9
12:26:42 INFO - PROCESS | 1164 |
12:26:42 INFO - PROCESS | 1164 | [#5] yui.html Cycles:180 Average:15534.39 Median:469.56 stddev:20868.56 (4444.3%) stddev-sans-first:20896.66
12:26:42 INFO - PROCESS | 1164 | Values: 522.6 545.1 590.7 599.5 599.8 12683.8 13317.3 13703.9 13958.5 14011.6 10400.6 11724.0 11749.1 12310.3 12368.7 535.4 539.1 539.8 539.9 543.1 527.6 544.1 544.4 544.5 544.7 572.4 572.6 573.9 574.2 574.3 567.8 569.0 570.0 570.6 571.1 413.8 414.1 414.6 414.7 415.8 10931.8 11032.5 11044.0 11053.9 11064.8 10460.9 10527.3 10546.1 10701.3 10776.2 9101.3 9239.1 9298.8 9497.1 9642.9 3178.9 3214.5 3262.1 3270.9 3357.3 59669.0 59693.0 60139.0 61078.7 61428.7 7992.8 8046.7 8113.4 8158.4 8246.0 13112.1 13299.4 13420.5 13447.2 13452.5 6553.8 6579.4 6632.7 6788.1 6831.6 3765.6 3786.7 3818.2 3856.7 3954.5 2292.4 2328.5 2344.5 2345.7 2352.9 469.3 469.8 470.3 477.0 482.4 545.5 545.9 562.5 575.7 583.7 7864.2 8059.5 8104.7 8149.9 8194.0 550.9 562.9 572.1 580.6 602.7 3865.4 3960.6 4033.6 4049.6 4071.2 73099.6 75175.6 77174.0 77716.6 77824.6 55281.0 67132.7 69294.0 69875.3 70942.6 55447.0 55761.0 59399.7 61060.0 63910.0 7784.6 8072.3 8188.0 8342.8 8629.1 39185.8 40743.8 41821.8 41854.2 41914.2 52300.5 56123.4 56183.7 56821.0 57033.0 10724.5 10948.5 10969.5 11098.3 11117.1 12528.9 12803.2 12872.4 13089.8 13106.3 11661.6 11950.9 12063.4 12117.3 12245.5 12113.9 12192.7 12433.4 12477.3 12869.1 11711.0 11830.0 11978.0 12088.9 12153.5 10419.6 10460.6 10700.5 10789.8 10871.1 10325.0 10414.7 10502.0 10521.3 10537.7
12:26:42 INFO - PROCESS | 1164 | -------- Summary: end --------
12:26:42 INFO - PROCESS | 1164 |
12:26:42 INFO - TEST-INFO | 1164: exit 0
12:26:43 INFO - TEST-OK | dromaeo_css | took 1121354ms
12:26:43 INFO - TEST-START | kraken
12:26:43 INFO - operating with platform_type : w7_
12:26:43 INFO - Initialising browser for kraken test...
12:26:43 INFO - TEST-INFO | started process 1224 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpbmyv9n\profile http://localhost:49620/getInfo.html)
12:26:48 INFO - PROCESS | 1224 | __metrics Screen width/height:1600/1200
12:26:48 INFO - PROCESS | 1224 | colorDepth:24
12:26:48 INFO - PROCESS | 1224 | Browser inner width/height: 1010/674
12:26:48 INFO - PROCESS | 1224 | __metrics
12:26:48 INFO - PROCESS | 1224 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows"
12:26:48 INFO - PROCESS | 1224 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 205: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
12:26:49 INFO - PROCESS | 1224 | console.error:
12:26:49 INFO - PROCESS | 1224 | Message: Error: Connection closed before committing the transaction.
12:26:49 INFO - PROCESS | 1224 | Stack:
12:26:49 INFO - PROCESS | 1224 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:937:23
12:26:49 INFO - PROCESS | 1224 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7
12:26:49 INFO - PROCESS | 1224 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11
12:26:49 INFO - PROCESS | 1224 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7
12:26:49 INFO - PROCESS | 1224 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7
12:26:49 INFO - PROCESS | 1224 | TaskImpl_run@resource://gre/modules/Task.jsm:327:15
12:26:49 INFO - PROCESS | 1224 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9
12:26:49 INFO - PROCESS | 1224 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15
12:26:49 INFO - PROCESS | 1224 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5
12:26:49 INFO - PROCESS | 1224 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29
12:26:49 INFO - PROCESS | 1224 | Barrier/this.client.addBlocker/promise13.3%
12:27:46 INFO - TinderboxPrint: I/O read bytes / time
237,240,320 / 2,826,070
12:27:46 INFO - TinderboxPrint: I/O write bytes / time
491,188,736 / 3,747,260
12:27:46 INFO - TinderboxPrint: CPU idle
8,247.6 (86.7%)
12:27:46 INFO - TinderboxPrint: CPU user
1,208.8 (12.7%)
12:27:46 INFO - install - Wall time: 3s; CPU: 11.0%; Read bytes: 0; Write bytes: 34748416; Read time: 0; Write time: 57780
12:27:46 INFO - run-tests - Wall time: 1188s; CPU: 13.0%; Read bytes: 237174784; Write bytes: 419014144; Read time: 2823330; Write time: 3484610
12:27:47 INFO - Running post-run listener: _upload_blobber_files
12:27:47 INFO - Blob upload gear active.
12:27:47 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir.
12:27:47 INFO - Files from C:\slave\test\build\blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com
12:27:47 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']
12:27:47 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
12:27:47 INFO - (blobuploader) - INFO - Open directory for files ...
12:27:47 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log ...
12:27:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
12:27:47 INFO - (blobuploader) - INFO - Uploading, attempt #1.
12:27:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded
12:27:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
12:27:48 INFO - (blobuploader) - INFO - Done attempting.
12:27:48 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log ...
12:27:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
12:27:48 INFO - (blobuploader) - INFO - Uploading, attempt #1.
12:27:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded
12:27:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
12:27:50 INFO - (blobuploader) - INFO - Done attempting.
12:27:50 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ...
12:27:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
12:27:50 INFO - (blobuploader) - INFO - Uploading, attempt #1.
12:27:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
12:27:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
12:27:58 INFO - (blobuploader) - INFO - Done attempting.
12:27:58 INFO - (blobuploader) - INFO - Iteration through files over.
12:27:58 INFO - Return code: 0
12:27:58 INFO - rmtree: C:\slave\test\build\uploaded_files.json
12:27:58 INFO - Using _rmtree_windows ...
12:27:58 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1
12:27:58 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f0c2e5816e20f42d79b3c1036c90ea1f23d219a0b8aa40350d2cacdd1434f742b5c103bace659050b6ee43ca60e050f5f5784fb4ed4e5763fe45ddb02aac8ec4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f2673b66433f2051f46d049975b8debab057c9f2da4f5970fd13fc8fc5c0519de6439d998c8f9088a0591a833836f9c3bf11dc1973311068fbdb46f9e782c37a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cdb1a56333bf6433a5da65fccfe51385044cb4d6b953b2a8d5407c87145183a60b5b2dd3070b3d22b6a95a27375e3f9918a0378eb5023342880b76a29b8e40db"}
12:27:58 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files
12:27:58 INFO - Writing to file C:\slave\test\properties\blobber_files
12:27:58 INFO - Contents:
12:27:58 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f0c2e5816e20f42d79b3c1036c90ea1f23d219a0b8aa40350d2cacdd1434f742b5c103bace659050b6ee43ca60e050f5f5784fb4ed4e5763fe45ddb02aac8ec4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f2673b66433f2051f46d049975b8debab057c9f2da4f5970fd13fc8fc5c0519de6439d998c8f9088a0591a833836f9c3bf11dc1973311068fbdb46f9e782c37a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cdb1a56333bf6433a5da65fccfe51385044cb4d6b953b2a8d5407c87145183a60b5b2dd3070b3d22b6a95a27375e3f9918a0378eb5023342880b76a29b8e40db"}
12:27:58 INFO - Running post-run listener: copy_logs_to_upload_dir
12:27:58 INFO - Copying logs to upload dir...
12:27:58 INFO - mkdir: C:\slave\test\build\upload\logs
12:27:58 INFO - Copying logs to upload dir...
12:27:58 INFO - Using _rmtree_windows ...
12:27:58 INFO - Using _rmtree_windows ...
12:27:58 INFO - Using _rmtree_windows ...
12:27:58 INFO - Using _rmtree_windows ...
12:27:58 INFO - Using _rmtree_windows ...
12:27:58 INFO - Using _rmtree_windows ...
12:27:58 INFO - Using _rmtree_windows ...
program finished with exit code 0
elapsedTime=1437.195000
========= master_lag: 0.39 =========
========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 57 secs) (at 2016-10-29 12:27:58.635203) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-29 12:27:58.641170) =========
'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-114
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-114
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-114
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/f0c2e5816e20f42d79b3c1036c90ea1f23d219a0b8aa40350d2cacdd1434f742b5c103bace659050b6ee43ca60e050f5f5784fb4ed4e5763fe45ddb02aac8ec4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f2673b66433f2051f46d049975b8debab057c9f2da4f5970fd13fc8fc5c0519de6439d998c8f9088a0591a833836f9c3bf11dc1973311068fbdb46f9e782c37a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cdb1a56333bf6433a5da65fccfe51385044cb4d6b953b2a8d5407c87145183a60b5b2dd3070b3d22b6a95a27375e3f9918a0378eb5023342880b76a29b8e40db"}
build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477758605/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/1477758605/firefox-52.0a1.en-US.win32.zip'
blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f0c2e5816e20f42d79b3c1036c90ea1f23d219a0b8aa40350d2cacdd1434f742b5c103bace659050b6ee43ca60e050f5f5784fb4ed4e5763fe45ddb02aac8ec4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f2673b66433f2051f46d049975b8debab057c9f2da4f5970fd13fc8fc5c0519de6439d998c8f9088a0591a833836f9c3bf11dc1973311068fbdb46f9e782c37a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cdb1a56333bf6433a5da65fccfe51385044cb4d6b953b2a8d5407c87145183a60b5b2dd3070b3d22b6a95a27375e3f9918a0378eb5023342880b76a29b8e40db"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-29 12:27:58.761624) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-29 12:27:58.761968) =========
'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-114
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-114
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-114
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-29 12:27:58.877617) =========
========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-10-29 12:27:58.877945) =========
========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-10-29 12:27:59.400093) =========
========= Total master_lag: 0.55 =========