builder: fx-team_win7_ix_test-dromaeojs-pgo
slave: t-w732-ix-055
starttime: 1476504842.25
results: success (0)
buildid: 20161014211400
builduid: aa6d78251e60439491b2b688bcdeb267
revision: 45f9fc18cf3dab70ba8d1982f40fea9246ae60b9
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-14 21:14:02.249492) =========
master: http://buildbot-master119.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-14 21:14:02.249959) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-14 21:14:02.250238) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-14 21:14:02.376266) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-14 21:14:02.376851) =========
'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-055
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-055
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-055
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
--21:14:03-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
=> `archiver_client.py'
Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25
Connecting to hg.mozilla.org|63.245.215.102|:443... connected.
WARNING: Certificate verification error for hg.mozilla.org: certificate signature failure
HTTP request sent, awaiting response... 200 Script output follows
Length: 12,179 (12K) [text/x-python]
0K .......... . 100% 12.71 MB/s
21:14:03 (12.71 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=1.025000
========= master_lag: 0.08 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-14 21:14:03.478597) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 10 secs) (at 2016-10-14 21:14:03.479163) =========
'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-055
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-055
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-055
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=10.301000
========= master_lag: 0.03 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 10 secs) (at 2016-10-14 21:14:13.810512) =========
========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-10-14 21:14:13.810816) =========
'bash' '-c' u'python archiver_client.py mozharness --repo integration/fx-team --rev 45f9fc18cf3dab70ba8d1982f40fea9246ae60b9 --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 45f9fc18cf3dab70ba8d1982f40fea9246ae60b9 --destination scripts --debug']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-055
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-055
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-055
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
2016-10-14 21:14:14,226 truncating revision to first 12 chars
2016-10-14 21:14:14,226 Setting DEBUG logging.
2016-10-14 21:14:14,226 attempt 1/10
2016-10-14 21:14:14,226 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/45f9fc18cf3d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2016-10-14 21:14:14,717 unpacking tar archive at: fx-team-45f9fc18cf3d/testing/mozharness/
2016-10-14 21:14:14,835 skipping symlink on windows: configs/single_locale/linux32.py
program finished with exit code 0
elapsedTime=1.229000
========= master_lag: 3.63 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-10-14 21:14:18.667059) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-14 21:14:18.667384) =========
script_repo_revision: 45f9fc18cf3dab70ba8d1982f40fea9246ae60b9
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-14 21:14:18.667781) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-14 21:14:18.668043) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-14 21:14:18.706044) =========
========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 40 secs) (at 2016-10-14 21:14:18.706418) =========
'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-055
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-055
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-055
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
21:14:19 INFO - MultiFileLogger online at 20161014 21:14:19 in C:\slave\test
21:14:19 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
21:14:19 INFO - Dumping config to C:\slave\test\logs\localconfig.json.
21:14:19 INFO - {'append_to_log': False,
21:14:19 INFO - 'base_work_dir': 'C:\\slave\\test',
21:14:19 INFO - 'blob_upload_branch': 'Fx-Team',
21:14:19 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt',
21:14:19 INFO - 'branch': 'Fx-Team',
21:14:19 INFO - 'buildbot_json_path': 'buildprops.json',
21:14:19 INFO - 'config_files': ('talos/windows_config.py',),
21:14:19 INFO - 'default_actions': ('clobber',
21:14:19 INFO - 'read-buildbot-config',
21:14:19 INFO - 'download-and-extract',
21:14:19 INFO - 'populate-webroot',
21:14:19 INFO - 'create-virtualenv',
21:14:19 INFO - 'install',
21:14:19 INFO - 'run-tests'),
21:14:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
21:14:19 INFO - 'download_minidump_stackwalk': True,
21:14:19 INFO - 'download_symbols': 'ondemand',
21:14:19 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python',
21:14:19 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'),
21:14:19 INFO - 'hg': 'c:/mozilla-build/hg/hg',
21:14:19 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python',
21:14:19 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'),
21:14:19 INFO - 'python': 'c:/mozilla-build/python27/python.exe',
21:14:19 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe',
21:14:19 INFO - 'C:/mozilla-build/tooltool.py'),
21:14:19 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe',
21:14:19 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')},
21:14:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
21:14:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
21:14:19 INFO - 'installer_path': 'installer.exe',
21:14:19 INFO - 'log_level': 'info',
21:14:19 INFO - 'log_name': 'talos',
21:14:19 INFO - 'log_to_console': True,
21:14:19 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe',
21:14:19 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe',
21:14:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest',
21:14:19 INFO - 'opt_config_files': (),
21:14:19 INFO - 'pip_index': False,
21:14:19 INFO - 'sps_profile': False,
21:14:19 INFO - 'sps_profile_interval': 0,
21:14:19 INFO - 'suite': 'dromaeojs',
21:14:19 INFO - 'system_bits': '32',
21:14:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
21:14:19 INFO - 'title': 'T-W732-IX-055',
21:14:19 INFO - 'use_talos_json': True,
21:14:19 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'),
21:14:19 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv',
21:14:19 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll',
21:14:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
21:14:19 INFO - 'work_dir': 'build'}
21:14:19 INFO - [mozharness: 2016-10-15 04:14:19.309000Z] Running clobber step.
21:14:19 INFO - Running pre-action listener: _resource_record_pre_action
21:14:19 INFO - Running main action method: clobber
21:14:19 INFO - rmtree: C:\slave\test\build
21:14:19 INFO - Using _rmtree_windows ...
21:14:19 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1
21:16:22 INFO - Running post-action listener: _resource_record_post_action
21:16:22 INFO - [mozharness: 2016-10-15 04:16:22.443000Z] Finished clobber step (success)
21:16:22 INFO - [mozharness: 2016-10-15 04:16:22.443000Z] Running read-buildbot-config step.
21:16:22 INFO - Running pre-action listener: _resource_record_pre_action
21:16:22 INFO - Running main action method: read_buildbot_config
21:16:22 INFO - Using buildbot properties:
21:16:22 INFO - {
21:16:22 INFO - "project": "",
21:16:22 INFO - "product": "firefox",
21:16:22 INFO - "script_repo_revision": "production",
21:16:22 INFO - "builddir": "fx-team_win7_ix_test-dromaeojs",
21:16:22 INFO - "repository": "",
21:16:22 INFO - "buildername": "Windows 7 32-bit fx-team pgo talos dromaeojs",
21:16:22 INFO - "buildid": "20161014211400",
21:16:22 INFO - "stage_platform": "win32-pgo",
21:16:22 INFO - "basedir": "C:\\slave\\test",
21:16:22 INFO - "buildnumber": 40,
21:16:22 INFO - "platform": "win7_ix",
21:16:22 INFO - "master": "http://buildbot-master119.bb.releng.scl3.mozilla.com:8201/",
21:16:22 INFO - "slavebuilddir": "test",
21:16:22 INFO - "branch": "fx-team",
21:16:22 INFO - "revision": "45f9fc18cf3dab70ba8d1982f40fea9246ae60b9",
21:16:22 INFO - "slavename": "t-w732-ix-055",
21:16:22 INFO - "builduid": "aa6d78251e60439491b2b688bcdeb267",
21:16:22 INFO - "repo_path": "integration/fx-team"
21:16:22 INFO - }
21:16:22 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476495004/firefox-52.0a1.en-US.win32.test_packages.json.
21:16:22 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476495004/firefox-52.0a1.en-US.win32.zip.
21:16:22 INFO - Running post-action listener: _resource_record_post_action
21:16:22 INFO - [mozharness: 2016-10-15 04:16:22.446000Z] Finished read-buildbot-config step (success)
21:16:22 INFO - [mozharness: 2016-10-15 04:16:22.447000Z] Running download-and-extract step.
21:16:22 INFO - Running pre-action listener: _resource_record_pre_action
21:16:22 INFO - Running main action method: download_and_extract
21:16:22 INFO - mkdir: C:\slave\test\build\tests
21:16:22 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')]}
21:16:22 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476495004/firefox-52.0a1.en-US.win32.test_packages.json
21:16:22 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476495004/firefox-52.0a1.en-US.win32.test_packages.json to C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json
21:16:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476495004/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
21:16:22 INFO - Downloaded 1218 bytes.
21:16:22 INFO - Reading from file C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json
21:16:22 INFO - Using the following test package requirements:
21:16:22 INFO - {u'common': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
21:16:22 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
21:16:22 INFO - u'firefox-52.0a1.en-US.win32.cppunittest.tests.zip'],
21:16:22 INFO - u'gtest': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
21:16:22 INFO - u'jittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
21:16:22 INFO - u'jsshell-win32.zip'],
21:16:22 INFO - u'mochitest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
21:16:22 INFO - u'firefox-52.0a1.en-US.win32.mochitest.tests.zip'],
21:16:22 INFO - u'mozbase': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
21:16:22 INFO - u'reftest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
21:16:22 INFO - u'firefox-52.0a1.en-US.win32.reftest.tests.zip'],
21:16:22 INFO - u'talos': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
21:16:22 INFO - u'firefox-52.0a1.en-US.win32.talos.tests.zip'],
21:16:22 INFO - u'web-platform': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
21:16:22 INFO - u'firefox-52.0a1.en-US.win32.web-platform.tests.zip'],
21:16:22 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
21:16:22 INFO - u'firefox-52.0a1.en-US.win32.xpcshell.tests.zip']}
21:16:22 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip'] for test suite category: common
21:16:22 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/1476495004/firefox-52.0a1.en-US.win32.common.tests.zip
21:16:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476495004/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1
21:16:22 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476495004/firefox-52.0a1.en-US.win32.common.tests.zip into memory
21:16:23 INFO - Http code: 200
21:16:23 INFO - via: 1.1 0302e8c462515ae281b72d9c815a5698.cloudfront.net (CloudFront)
21:16:23 INFO - x-amz-cf-id: VCWRLQTah3gpu6cBhykydq8Lg1znCgYEI9PmTAPBr5-3kboYNmTTww==
21:16:23 INFO - x-amz-replication-status: COMPLETED
21:16:23 INFO - x-amz-version-id: 5fGDWjF7v3ebwa_YTjLDquYt.2w0UhLE
21:16:24 INFO - Expected file size: 17275188
21:16:24 INFO - Obtained file size: 17275188
21:17:10 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip', u'firefox-52.0a1.en-US.win32.talos.tests.zip'] for test suite category: talos
21:17:10 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/1476495004/firefox-52.0a1.en-US.win32.common.tests.zip
21:17:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476495004/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1
21:17:10 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476495004/firefox-52.0a1.en-US.win32.common.tests.zip into memory
21:17:10 INFO - Http code: 200
21:17:10 INFO - via: 1.1 76bce8bb4fbd102fc0b3aa2e41094b79.cloudfront.net (CloudFront)
21:17:10 INFO - x-amz-cf-id: Tgj8yRv9rSNkWeKgS4BGE9zgxcrqtPScfFoNtnHZkb28Xthnj0RZfA==
21:17:10 INFO - x-amz-replication-status: COMPLETED
21:17:10 INFO - x-amz-version-id: 5fGDWjF7v3ebwa_YTjLDquYt.2w0UhLE
21:17:11 INFO - Expected file size: 17275188
21:17:11 INFO - Obtained file size: 17275188
21:17:27 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/1476495004/firefox-52.0a1.en-US.win32.talos.tests.zip
21:17:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476495004/firefox-52.0a1.en-US.win32.talos.tests.zip'}, attempt #1
21:17:27 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476495004/firefox-52.0a1.en-US.win32.talos.tests.zip into memory
21:17:27 INFO - Http code: 200
21:17:27 INFO - via: 1.1 1b52a5dd431f9e3c81753e61dfdf467a.cloudfront.net (CloudFront)
21:17:27 INFO - x-amz-cf-id: QPJHKbtobFZms87_kgAPEZIa1b_UGKXHxCp0BDpadUVkY6pHIJ1l6w==
21:17:27 INFO - x-amz-replication-status: COMPLETED
21:17:27 INFO - x-amz-version-id: mE53th7npcrKTPa3Up_VS7XW.zBEUQaa
21:17:28 INFO - Expected file size: 14409028
21:17:28 INFO - Obtained file size: 14409028
21:17:45 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')]}
21:17:45 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476495004/firefox-52.0a1.en-US.win32.zip
21:17:45 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476495004/firefox-52.0a1.en-US.win32.zip to C:\slave\test\build\firefox-52.0a1.en-US.win32.zip
21:17:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476495004/firefox-52.0a1.en-US.win32.zip', 'file_name': 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.zip'}, attempt #1
21:17:46 INFO - Downloaded 54331519 bytes.
21:17:46 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476495004/firefox-52.0a1.en-US.win32.zip
21:17:46 INFO - mkdir: C:\slave\test\properties
21:17:46 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url
21:17:46 INFO - Writing to file C:\slave\test\properties\build_url
21:17:46 INFO - Contents:
21:17:46 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476495004/firefox-52.0a1.en-US.win32.zip
21:17:47 INFO - Running post-action listener: _resource_record_post_action
21:17:47 INFO - Running post-action listener: set_extra_try_arguments
21:17:47 INFO - [mozharness: 2016-10-15 04:17:47.021000Z] Finished download-and-extract step (success)
21:17:47 INFO - [mozharness: 2016-10-15 04:17:47.021000Z] Running populate-webroot step.
21:17:47 INFO - Running pre-action listener: _resource_record_pre_action
21:17:47 INFO - Running main action method: populate_webroot
21:17:47 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']},
21:17:47 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']},
21:17:47 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome',
21:17:47 INFO - u'--tppagecycles',
21:17:47 INFO - u'7'],
21:17:47 INFO - u'tests': [u'tsvgm']}},
21:17:47 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'],
21:17:47 INFO - u'tests': [u'tresize', u'tcanvasmark']},
21:17:47 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']},
21:17:47 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'],
21:17:47 INFO - u'tests': [u'dromaeo_css', u'kraken']},
21:17:47 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']},
21:17:47 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
21:17:47 INFO - u'talos_options': [u'--disable-e10s'],
21:17:47 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
21:17:47 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
21:17:47 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
21:17:47 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
21:17:47 INFO - u'talos_options': [u'--disable-e10s'],
21:17:47 INFO - u'tests': [u'damp', u'tps']},
21:17:47 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
21:17:47 INFO - u'tests': [u'damp', u'tps']},
21:17:47 INFO - u'g3': {u'talos_options': [u'--disable-e10s'],
21:17:47 INFO - u'tests': [u'dromaeo_dom']},
21:17:47 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']},
21:17:47 INFO - u'g4': {u'talos_options': [u'--disable-e10s'],
21:17:47 INFO - u'tests': [u'basic_compositor_video']},
21:17:47 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']},
21:17:47 INFO - u'other': {u'talos_options': [u'--disable-e10s'],
21:17:47 INFO - u'tests': [u'a11yr',
21:17:47 INFO - u'ts_paint',
21:17:47 INFO - u'tpaint',
21:17:47 INFO - u'sessionrestore',
21:17:47 INFO - u'sessionrestore_no_auto_restore',
21:17:47 INFO - u'tabpaint']},
21:17:47 INFO - u'other-e10s': {u'tests': [u'a11yr',
21:17:47 INFO - u'ts_paint',
21:17:47 INFO - u'tpaint',
21:17:47 INFO - u'sessionrestore',
21:17:47 INFO - u'sessionrestore_no_auto_restore',
21:17:47 INFO - u'tabpaint']},
21:17:47 INFO - u'other-e10s_l64': {u'tests': [u'a11yr',
21:17:47 INFO - u'ts_paint',
21:17:47 INFO - u'tpaint',
21:17:47 INFO - u'sessionrestore',
21:17:47 INFO - u'sessionrestore_no_auto_restore',
21:17:47 INFO - u'tabpaint']},
21:17:47 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr',
21:17:47 INFO - u'ts_paint',
21:17:47 INFO - u'tpaint',
21:17:47 INFO - u'sessionrestore',
21:17:47 INFO - u'sessionrestore_no_auto_restore',
21:17:47 INFO - u'tabpaint']},
21:17:47 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'],
21:17:47 INFO - u'tests': [u'a11yr',
21:17:47 INFO - u'ts_paint',
21:17:47 INFO - u'tpaint',
21:17:47 INFO - u'sessionrestore',
21:17:47 INFO - u'sessionrestore_no_auto_restore',
21:17:47 INFO - u'tabpaint']},
21:17:47 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'],
21:17:47 INFO - u'tests': [u'a11yr',
21:17:47 INFO - u'ts_paint',
21:17:47 INFO - u'tpaint',
21:17:47 INFO - u'sessionrestore',
21:17:47 INFO - u'sessionrestore_no_auto_restore',
21:17:47 INFO - u'tabpaint']},
21:17:47 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'],
21:17:47 INFO - u'tests': [u'tsvgx',
21:17:47 INFO - u'tsvgr_opacity',
21:17:47 INFO - u'tart',
21:17:47 INFO - u'tscrollx',
21:17:47 INFO - u'cart']},
21:17:47 INFO - u'svgr-e10s': {u'tests': [u'tsvgx',
21:17:47 INFO - u'tsvgr_opacity',
21:17:47 INFO - u'tart',
21:17:47 INFO - u'tscrollx',
21:17:47 INFO - u'cart']},
21:17:47 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
21:17:47 INFO - u'talos_options': [u'--disable-e10s'],
21:17:47 INFO - u'tests': [u'tp5o']},
21:17:47 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
21:17:47 INFO - u'tests': [u'tp5o']},
21:17:47 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
21:17:47 INFO - u'talos_options': [u'--disable-e10s',
21:17:47 INFO - u'--xperf_path',
21:17:47 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
21:17:47 INFO - u'tests': [u'tp5n']},
21:17:47 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
21:17:47 INFO - u'talos_options': [u'--xperf_path',
21:17:47 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
21:17:47 INFO - u'tests': [u'tp5n']}},
21:17:47 INFO - u'talos.zip': {u'path': u'',
21:17:47 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}}
21:17:47 INFO - Running post-action listener: _resource_record_post_action
21:17:47 INFO - [mozharness: 2016-10-15 04:17:47.068000Z] Finished populate-webroot step (success)
21:17:47 INFO - [mozharness: 2016-10-15 04:17:47.068000Z] Running create-virtualenv step.
21:17:47 INFO - Running pre-action listener: _resource_record_pre_action
21:17:47 INFO - Running main action method: create_virtualenv
21:17:47 INFO - Creating virtualenv C:\slave\test\build/venv
21:17:47 INFO - mkdir: C:\slave\test\build\venv\Scripts
21:17:47 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll
21:17:47 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'!
21:17:47 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
21:17:47 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv
21:18:06 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe
21:18:09 INFO - Installing distribute......................................................................................................................................................................................done.
21:18:11 INFO - Return code: 0
21:18:11 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version']
21:18:11 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version
21:18:11 INFO - Reading from file tmpfile_stdout
21:18:11 INFO - Output received:
21:18:11 INFO - pip 0.8.2 from c:\slave\test\build\venv\lib\site-packages\pip-0.8.2-py2.7.egg (python 2.7)
21:18:11 INFO - Using _rmtree_windows ...
21:18:11 INFO - Using _rmtree_windows ...
21:18:11 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv
21:18:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
21:18:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
21:18:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
21:18:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
21:18:11 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01968640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019A2E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018E2B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019BCE90>, '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 0x013BA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A28520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-055', 'USERDOMAIN': 'T-W732-IX-055', '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-055', '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
21:18:11 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in C:\slave\test\build
21:18:11 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5
21:18:11 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
21:18:11 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
21:18:11 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
21:18:11 INFO - 'COMPUTERNAME': 'T-W732-IX-055',
21:18:11 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
21:18:11 INFO - 'CYGWINBASE': 'C:\\cygwin',
21:18:11 INFO - 'DCLOCATION': 'SCL3',
21:18:11 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
21:18:11 INFO - 'FP_NO_HOST_CHECK': 'NO',
21:18:11 INFO - 'HOMEDRIVE': 'C:',
21:18:11 INFO - 'HOMEPATH': '\\Users\\cltbld',
21:18:11 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
21:18:11 INFO - 'KTS_VERSION': '1.19c',
21:18:11 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
21:18:11 INFO - 'LOGONSERVER': '\\\\T-W732-IX-055',
21:18:11 INFO - 'MONDIR': 'C:\\Monitor_config\\',
21:18:11 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
21:18:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
21:18:11 INFO - 'MOZ_NO_REMOTE': '1',
21:18:11 INFO - 'NO_EM_RESTART': '1',
21:18:11 INFO - 'NUMBER_OF_PROCESSORS': '8',
21:18:11 INFO - 'OS': 'Windows_NT',
21:18:11 INFO - 'OURDRIVE': 'C:',
21:18:11 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
21:18:11 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
21:18:11 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
21:18:11 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
21:18:11 INFO - 'PROCESSOR_LEVEL': '6',
21:18:11 INFO - 'PROCESSOR_REVISION': '1e05',
21:18:11 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
21:18:11 INFO - 'PROGRAMFILES': 'C:\\Program Files',
21:18:11 INFO - 'PROMPT': '$P$G',
21:18:11 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
21:18:11 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
21:18:11 INFO - 'PUBLIC': 'C:\\Users\\Public',
21:18:11 INFO - 'PWD': 'C:\\slave\\test',
21:18:11 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
21:18:11 INFO - 'SLAVEDIR': 'C:\\slave\\',
21:18:11 INFO - 'SYSTEMDRIVE': 'C:',
21:18:11 INFO - 'SYSTEMROOT': 'C:\\windows',
21:18:11 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:18:11 INFO - 'TEST1': 'testie',
21:18:11 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:18:11 INFO - 'USERDOMAIN': 'T-W732-IX-055',
21:18:11 INFO - 'USERNAME': 'cltbld',
21:18:11 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
21:18:11 INFO - 'WINDIR': 'C:\\windows',
21:18:11 INFO - 'WINDOWS_TRACING_FLAGS': '3',
21:18:11 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
21:18:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
21:18:15 INFO - Ignoring indexes: http://pypi.python.org/simple/
21:18:15 INFO - Downloading/unpacking pip>=1.5
21:18:19 INFO - Running setup.py egg_info for package pip
21:18:19 INFO - warning: no files found matching 'pip\cacert.pem'
21:18:19 INFO - warning: no files found matching '*.html' under directory 'docs'
21:18:19 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
21:18:19 INFO - no previously-included directories found matching 'docs\_build\_sources'
21:18:19 INFO - Installing collected packages: pip
21:18:19 INFO - Found existing installation: pip 0.8.2
21:18:19 INFO - Uninstalling pip:
21:18:19 INFO - Successfully uninstalled pip
21:18:19 INFO - Running setup.py install for pip
21:18:19 INFO - warning: no files found matching 'pip\cacert.pem'
21:18:19 INFO - warning: no files found matching '*.html' under directory 'docs'
21:18:19 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
21:18:19 INFO - no previously-included directories found matching 'docs\_build\_sources'
21:18:19 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts
21:18:19 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts
21:18:19 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts
21:18:19 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts
21:18:19 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts
21:18:19 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts
21:18:19 INFO - Successfully installed pip
21:18:19 INFO - Cleaning up...
21:18:19 INFO - Return code: 0
21:18:19 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv
21:18:19 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')]}
21:18:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
21:18:19 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')]}
21:18:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
21:18:19 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 0x01968640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019A2E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018E2B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019BCE90>, '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 0x013BA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A28520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-055', 'USERDOMAIN': 'T-W732-IX-055', '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-055', '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
21:18:19 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
21:18:19 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
21:18:19 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
21:18:19 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
21:18:19 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
21:18:19 INFO - 'COMPUTERNAME': 'T-W732-IX-055',
21:18:19 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
21:18:19 INFO - 'CYGWINBASE': 'C:\\cygwin',
21:18:19 INFO - 'DCLOCATION': 'SCL3',
21:18:19 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
21:18:19 INFO - 'FP_NO_HOST_CHECK': 'NO',
21:18:19 INFO - 'HOMEDRIVE': 'C:',
21:18:19 INFO - 'HOMEPATH': '\\Users\\cltbld',
21:18:19 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
21:18:19 INFO - 'KTS_VERSION': '1.19c',
21:18:19 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
21:18:19 INFO - 'LOGONSERVER': '\\\\T-W732-IX-055',
21:18:19 INFO - 'MONDIR': 'C:\\Monitor_config\\',
21:18:19 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
21:18:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
21:18:19 INFO - 'MOZ_NO_REMOTE': '1',
21:18:19 INFO - 'NO_EM_RESTART': '1',
21:18:19 INFO - 'NUMBER_OF_PROCESSORS': '8',
21:18:19 INFO - 'OS': 'Windows_NT',
21:18:19 INFO - 'OURDRIVE': 'C:',
21:18:19 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;',
21:18:19 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
21:18:19 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
21:18:19 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
21:18:19 INFO - 'PROCESSOR_LEVEL': '6',
21:18:19 INFO - 'PROCESSOR_REVISION': '1e05',
21:18:19 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
21:18:19 INFO - 'PROGRAMFILES': 'C:\\Program Files',
21:18:19 INFO - 'PROMPT': '$P$G',
21:18:19 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
21:18:19 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
21:18:19 INFO - 'PUBLIC': 'C:\\Users\\Public',
21:18:19 INFO - 'PWD': 'C:\\slave\\test',
21:18:19 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
21:18:19 INFO - 'SLAVEDIR': 'C:\\slave\\',
21:18:19 INFO - 'SYSTEMDRIVE': 'C:',
21:18:19 INFO - 'SYSTEMROOT': 'C:\\windows',
21:18:19 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:18:19 INFO - 'TEST1': 'testie',
21:18:19 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:18:19 INFO - 'USERDOMAIN': 'T-W732-IX-055',
21:18:19 INFO - 'USERNAME': 'cltbld',
21:18:19 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
21:18:19 INFO - 'WINDIR': 'C:\\windows',
21:18:19 INFO - 'WINDOWS_TRACING_FLAGS': '3',
21:18:19 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
21:18:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
21:18:23 INFO - Ignoring indexes: https://pypi.python.org/simple/
21:18:23 INFO - Downloading/unpacking psutil>=3.1.1
21:18:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
21:18:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
21:18:25 INFO - Installing collected packages: psutil
21:18:25 INFO - Successfully installed psutil
21:18:25 INFO - Cleaning up...
21:18:25 INFO - Return code: 0
21:18:25 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv
21:18:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
21:18:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
21:18:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
21:18:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
21:18:25 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 0x01968640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019A2E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018E2B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019BCE90>, '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 0x013BA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A28520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-055', 'USERDOMAIN': 'T-W732-IX-055', '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-055', '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
21:18:25 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
21:18:25 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
21:18:25 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
21:18:25 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
21:18:25 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
21:18:25 INFO - 'COMPUTERNAME': 'T-W732-IX-055',
21:18:25 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
21:18:25 INFO - 'CYGWINBASE': 'C:\\cygwin',
21:18:25 INFO - 'DCLOCATION': 'SCL3',
21:18:25 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
21:18:25 INFO - 'FP_NO_HOST_CHECK': 'NO',
21:18:25 INFO - 'HOMEDRIVE': 'C:',
21:18:25 INFO - 'HOMEPATH': '\\Users\\cltbld',
21:18:25 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
21:18:25 INFO - 'KTS_VERSION': '1.19c',
21:18:25 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
21:18:25 INFO - 'LOGONSERVER': '\\\\T-W732-IX-055',
21:18:25 INFO - 'MONDIR': 'C:\\Monitor_config\\',
21:18:25 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
21:18:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
21:18:25 INFO - 'MOZ_NO_REMOTE': '1',
21:18:25 INFO - 'NO_EM_RESTART': '1',
21:18:25 INFO - 'NUMBER_OF_PROCESSORS': '8',
21:18:25 INFO - 'OS': 'Windows_NT',
21:18:25 INFO - 'OURDRIVE': 'C:',
21:18:25 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
21:18:25 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
21:18:25 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
21:18:25 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
21:18:25 INFO - 'PROCESSOR_LEVEL': '6',
21:18:25 INFO - 'PROCESSOR_REVISION': '1e05',
21:18:25 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
21:18:25 INFO - 'PROGRAMFILES': 'C:\\Program Files',
21:18:25 INFO - 'PROMPT': '$P$G',
21:18:25 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
21:18:25 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
21:18:25 INFO - 'PUBLIC': 'C:\\Users\\Public',
21:18:25 INFO - 'PWD': 'C:\\slave\\test',
21:18:25 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
21:18:25 INFO - 'SLAVEDIR': 'C:\\slave\\',
21:18:25 INFO - 'SYSTEMDRIVE': 'C:',
21:18:25 INFO - 'SYSTEMROOT': 'C:\\windows',
21:18:25 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:18:25 INFO - 'TEST1': 'testie',
21:18:25 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:18:25 INFO - 'USERDOMAIN': 'T-W732-IX-055',
21:18:25 INFO - 'USERNAME': 'cltbld',
21:18:25 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
21:18:25 INFO - 'WINDIR': 'C:\\windows',
21:18:25 INFO - 'WINDOWS_TRACING_FLAGS': '3',
21:18:25 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
21:18:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
21:18:26 INFO - Ignoring indexes: https://pypi.python.org/simple/
21:18:26 INFO - Downloading/unpacking mozsystemmonitor==0.3
21:18:26 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
21:18:26 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
21:18:27 INFO - Downloading mozsystemmonitor-0.3.tar.gz
21:18:27 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor
21:18:27 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)
21:18:27 INFO - Installing collected packages: mozsystemmonitor
21:18:27 INFO - Running setup.py install for mozsystemmonitor
21:18:27 INFO - Successfully installed mozsystemmonitor
21:18:27 INFO - Cleaning up...
21:18:27 INFO - Return code: 0
21:18:27 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv
21:18: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')]}
21:18:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
21:18: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')]}
21:18:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
21:18: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', '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 0x01968640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019A2E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018E2B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019BCE90>, '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 0x013BA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A28520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-055', 'USERDOMAIN': 'T-W732-IX-055', '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-055', '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
21:18: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', 'jsonschema==2.5.1'] in C:\slave\test\build
21:18: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 jsonschema==2.5.1
21:18:27 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
21:18:27 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
21:18:27 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
21:18:27 INFO - 'COMPUTERNAME': 'T-W732-IX-055',
21:18:27 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
21:18:27 INFO - 'CYGWINBASE': 'C:\\cygwin',
21:18:27 INFO - 'DCLOCATION': 'SCL3',
21:18:27 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
21:18:27 INFO - 'FP_NO_HOST_CHECK': 'NO',
21:18:27 INFO - 'HOMEDRIVE': 'C:',
21:18:27 INFO - 'HOMEPATH': '\\Users\\cltbld',
21:18:27 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
21:18:27 INFO - 'KTS_VERSION': '1.19c',
21:18:27 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
21:18:27 INFO - 'LOGONSERVER': '\\\\T-W732-IX-055',
21:18:27 INFO - 'MONDIR': 'C:\\Monitor_config\\',
21:18:27 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
21:18:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
21:18:27 INFO - 'MOZ_NO_REMOTE': '1',
21:18:27 INFO - 'NO_EM_RESTART': '1',
21:18:27 INFO - 'NUMBER_OF_PROCESSORS': '8',
21:18:27 INFO - 'OS': 'Windows_NT',
21:18:27 INFO - 'OURDRIVE': 'C:',
21:18: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;',
21:18:27 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
21:18:27 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
21:18:27 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
21:18:27 INFO - 'PROCESSOR_LEVEL': '6',
21:18:27 INFO - 'PROCESSOR_REVISION': '1e05',
21:18:27 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
21:18:27 INFO - 'PROGRAMFILES': 'C:\\Program Files',
21:18:27 INFO - 'PROMPT': '$P$G',
21:18:27 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
21:18:27 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
21:18:27 INFO - 'PUBLIC': 'C:\\Users\\Public',
21:18:27 INFO - 'PWD': 'C:\\slave\\test',
21:18:27 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
21:18:27 INFO - 'SLAVEDIR': 'C:\\slave\\',
21:18:27 INFO - 'SYSTEMDRIVE': 'C:',
21:18:27 INFO - 'SYSTEMROOT': 'C:\\windows',
21:18:27 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:18:27 INFO - 'TEST1': 'testie',
21:18:27 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:18:27 INFO - 'USERDOMAIN': 'T-W732-IX-055',
21:18:27 INFO - 'USERNAME': 'cltbld',
21:18:27 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
21:18:27 INFO - 'WINDIR': 'C:\\windows',
21:18:27 INFO - 'WINDOWS_TRACING_FLAGS': '3',
21:18:27 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
21:18:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
21:18:28 INFO - Ignoring indexes: https://pypi.python.org/simple/
21:18:28 INFO - Downloading/unpacking jsonschema==2.5.1
21:18:28 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
21:18:28 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
21:18:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl
21:18:29 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1)
21:18: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
21:18: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
21:18:29 INFO - Downloading functools32-3.2.3-2.tar.gz
21:18:29 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32
21:18:29 INFO - warning: no files found matching '*.txt'
21:18:29 INFO - no previously-included directories found matching 'build'
21:18:29 INFO - no previously-included directories found matching 'dist'
21:18:29 INFO - no previously-included directories found matching '.git*'
21:18:29 INFO - Installing collected packages: jsonschema, functools32
21:18:29 INFO - Running setup.py install for functools32
21:18:29 INFO - warning: no files found matching '*.txt'
21:18:29 INFO - no previously-included directories found matching 'build'
21:18:29 INFO - no previously-included directories found matching 'dist'
21:18:29 INFO - no previously-included directories found matching '.git*'
21:18:29 INFO - Successfully installed jsonschema functools32
21:18:29 INFO - Cleaning up...
21:18:30 INFO - Return code: 0
21:18:30 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv
21:18:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
21:18:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
21:18:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
21:18:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
21:18:30 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x01968640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019A2E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018E2B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019BCE90>, '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 0x013BA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A28520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-055', 'USERDOMAIN': 'T-W732-IX-055', '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-055', '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
21:18:30 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in C:\slave\test\build
21:18:30 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2
21:18:30 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
21:18:30 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
21:18:30 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
21:18:30 INFO - 'COMPUTERNAME': 'T-W732-IX-055',
21:18:30 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
21:18:30 INFO - 'CYGWINBASE': 'C:\\cygwin',
21:18:30 INFO - 'DCLOCATION': 'SCL3',
21:18:30 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
21:18:30 INFO - 'FP_NO_HOST_CHECK': 'NO',
21:18:30 INFO - 'HOMEDRIVE': 'C:',
21:18:30 INFO - 'HOMEPATH': '\\Users\\cltbld',
21:18:30 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
21:18:30 INFO - 'KTS_VERSION': '1.19c',
21:18:30 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
21:18:30 INFO - 'LOGONSERVER': '\\\\T-W732-IX-055',
21:18:30 INFO - 'MONDIR': 'C:\\Monitor_config\\',
21:18:30 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
21:18:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
21:18:30 INFO - 'MOZ_NO_REMOTE': '1',
21:18:30 INFO - 'NO_EM_RESTART': '1',
21:18:30 INFO - 'NUMBER_OF_PROCESSORS': '8',
21:18:30 INFO - 'OS': 'Windows_NT',
21:18:30 INFO - 'OURDRIVE': 'C:',
21:18:30 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
21:18:30 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
21:18:30 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
21:18:30 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
21:18:30 INFO - 'PROCESSOR_LEVEL': '6',
21:18:30 INFO - 'PROCESSOR_REVISION': '1e05',
21:18:30 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
21:18:30 INFO - 'PROGRAMFILES': 'C:\\Program Files',
21:18:30 INFO - 'PROMPT': '$P$G',
21:18:30 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
21:18:30 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
21:18:30 INFO - 'PUBLIC': 'C:\\Users\\Public',
21:18:30 INFO - 'PWD': 'C:\\slave\\test',
21:18:30 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
21:18:30 INFO - 'SLAVEDIR': 'C:\\slave\\',
21:18:30 INFO - 'SYSTEMDRIVE': 'C:',
21:18:30 INFO - 'SYSTEMROOT': 'C:\\windows',
21:18:30 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:18:30 INFO - 'TEST1': 'testie',
21:18:30 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:18:30 INFO - 'USERDOMAIN': 'T-W732-IX-055',
21:18:30 INFO - 'USERNAME': 'cltbld',
21:18:30 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
21:18:30 INFO - 'WINDIR': 'C:\\windows',
21:18:30 INFO - 'WINDOWS_TRACING_FLAGS': '3',
21:18:30 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
21:18:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
21:18:31 INFO - Ignoring indexes: https://pypi.python.org/simple/
21:18:31 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages
21:18:31 INFO - Cleaning up...
21:18:31 INFO - Return code: 0
21:18:31 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv
21:18: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')]}
21:18:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
21:18: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')]}
21:18:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
21:18: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 0x01968640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019A2E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018E2B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019BCE90>, '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 0x013BA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A28520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-055', 'USERDOMAIN': 'T-W732-IX-055', '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-055', '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
21:18: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
21:18: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
21:18:31 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
21:18:31 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
21:18:31 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
21:18:31 INFO - 'COMPUTERNAME': 'T-W732-IX-055',
21:18:31 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
21:18:31 INFO - 'CYGWINBASE': 'C:\\cygwin',
21:18:31 INFO - 'DCLOCATION': 'SCL3',
21:18:31 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
21:18:31 INFO - 'FP_NO_HOST_CHECK': 'NO',
21:18:31 INFO - 'HOMEDRIVE': 'C:',
21:18:31 INFO - 'HOMEPATH': '\\Users\\cltbld',
21:18:31 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
21:18:31 INFO - 'KTS_VERSION': '1.19c',
21:18:31 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
21:18:31 INFO - 'LOGONSERVER': '\\\\T-W732-IX-055',
21:18:31 INFO - 'MONDIR': 'C:\\Monitor_config\\',
21:18:31 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
21:18:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
21:18:31 INFO - 'MOZ_NO_REMOTE': '1',
21:18:31 INFO - 'NO_EM_RESTART': '1',
21:18:31 INFO - 'NUMBER_OF_PROCESSORS': '8',
21:18:31 INFO - 'OS': 'Windows_NT',
21:18:31 INFO - 'OURDRIVE': 'C:',
21:18: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;',
21:18:31 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
21:18:31 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
21:18:31 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
21:18:31 INFO - 'PROCESSOR_LEVEL': '6',
21:18:31 INFO - 'PROCESSOR_REVISION': '1e05',
21:18:31 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
21:18:31 INFO - 'PROGRAMFILES': 'C:\\Program Files',
21:18:31 INFO - 'PROMPT': '$P$G',
21:18:31 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
21:18:31 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
21:18:31 INFO - 'PUBLIC': 'C:\\Users\\Public',
21:18:31 INFO - 'PWD': 'C:\\slave\\test',
21:18:31 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
21:18:31 INFO - 'SLAVEDIR': 'C:\\slave\\',
21:18:31 INFO - 'SYSTEMDRIVE': 'C:',
21:18:31 INFO - 'SYSTEMROOT': 'C:\\windows',
21:18:31 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:18:31 INFO - 'TEST1': 'testie',
21:18:31 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:18:31 INFO - 'USERDOMAIN': 'T-W732-IX-055',
21:18:31 INFO - 'USERNAME': 'cltbld',
21:18:31 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
21:18:31 INFO - 'WINDIR': 'C:\\windows',
21:18:31 INFO - 'WINDOWS_TRACING_FLAGS': '3',
21:18:31 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
21:18:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
21:18:31 INFO - Ignoring indexes: https://pypi.python.org/simple/
21:18:31 INFO - Downloading/unpacking blobuploader==1.2.4
21:18:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
21:18:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
21:18:32 INFO - Downloading blobuploader-1.2.4.tar.gz
21:18:32 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader
21:18:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4)
21:18: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
21:18: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
21:18:32 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests
21:18:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4)
21:18: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
21:18: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
21:18:33 INFO - Downloading docopt-0.6.1.tar.gz
21:18:33 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt
21:18:33 INFO - Installing collected packages: blobuploader, requests, docopt
21:18:33 INFO - Running setup.py install for blobuploader
21:18:33 INFO - Running setup.py install for requests
21:18:34 INFO - Running setup.py install for docopt
21:18:34 INFO - Successfully installed blobuploader requests docopt
21:18:34 INFO - Cleaning up...
21:18:35 INFO - Return code: 0
21:18:35 INFO - Installing None into virtualenv C:\slave\test\build/venv
21:18:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
21:18:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
21:18:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
21:18:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
21:18:35 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 0x01968640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019A2E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018E2B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019BCE90>, '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 0x013BA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A28520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-055', 'USERDOMAIN': 'T-W732-IX-055', '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-055', '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
21:18:35 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
21:18:35 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
21:18:35 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
21:18:35 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
21:18:35 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
21:18:35 INFO - 'COMPUTERNAME': 'T-W732-IX-055',
21:18:35 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
21:18:35 INFO - 'CYGWINBASE': 'C:\\cygwin',
21:18:35 INFO - 'DCLOCATION': 'SCL3',
21:18:35 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
21:18:35 INFO - 'FP_NO_HOST_CHECK': 'NO',
21:18:35 INFO - 'HOMEDRIVE': 'C:',
21:18:35 INFO - 'HOMEPATH': '\\Users\\cltbld',
21:18:35 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
21:18:35 INFO - 'KTS_VERSION': '1.19c',
21:18:35 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
21:18:35 INFO - 'LOGONSERVER': '\\\\T-W732-IX-055',
21:18:35 INFO - 'MONDIR': 'C:\\Monitor_config\\',
21:18:35 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
21:18:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
21:18:35 INFO - 'MOZ_NO_REMOTE': '1',
21:18:35 INFO - 'NO_EM_RESTART': '1',
21:18:35 INFO - 'NUMBER_OF_PROCESSORS': '8',
21:18:35 INFO - 'OS': 'Windows_NT',
21:18:35 INFO - 'OURDRIVE': 'C:',
21:18:35 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
21:18:35 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
21:18:35 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
21:18:35 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
21:18:35 INFO - 'PROCESSOR_LEVEL': '6',
21:18:35 INFO - 'PROCESSOR_REVISION': '1e05',
21:18:35 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
21:18:35 INFO - 'PROGRAMFILES': 'C:\\Program Files',
21:18:35 INFO - 'PROMPT': '$P$G',
21:18:35 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
21:18:35 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
21:18:35 INFO - 'PUBLIC': 'C:\\Users\\Public',
21:18:35 INFO - 'PWD': 'C:\\slave\\test',
21:18:35 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
21:18:35 INFO - 'SLAVEDIR': 'C:\\slave\\',
21:18:35 INFO - 'SYSTEMDRIVE': 'C:',
21:18:35 INFO - 'SYSTEMROOT': 'C:\\windows',
21:18:35 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:18:35 INFO - 'TEST1': 'testie',
21:18:35 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:18:35 INFO - 'USERDOMAIN': 'T-W732-IX-055',
21:18:35 INFO - 'USERNAME': 'cltbld',
21:18:35 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
21:18:35 INFO - 'WINDIR': 'C:\\windows',
21:18:35 INFO - 'WINDOWS_TRACING_FLAGS': '3',
21:18:35 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
21:18:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
21:18:35 INFO - Ignoring indexes: https://pypi.python.org/simple/
21:18:35 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
21:18:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xe9uhh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
21:18:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
21:18:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wlndqq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
21:18:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
21:18:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-iwl4ja-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
21:18:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
21:18:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-3vzjy0-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
21:18:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
21:18:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-f0mooq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
21:18:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
21:18:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zqyfcl-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
21:18:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
21:18:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-n73kye-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
21:18:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
21:18:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hnpv9b-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
21:18:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
21:18:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-w21z1f-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
21:18:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
21:18:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bnr3fy-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
21:18:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
21:18:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-aq4vsf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
21:18:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
21:18:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dllixr-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
21:18:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
21:18:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jhzwpq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
21:18:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
21:18:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-5uenjj-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
21:18:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
21:18:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rdggrb-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
21:18:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
21:18:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-2_h1em-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
21:18:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
21:18:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jguo4k-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
21:18:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
21:18:38 INFO - Running setup.py install for manifestparser
21:18:38 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts
21:18:38 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts
21:18:38 INFO - Running setup.py install for mozcrash
21:18:39 INFO - Running setup.py install for mozdebug
21:18:39 INFO - Running setup.py install for mozdevice
21:18:39 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts
21:18:39 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts
21:18:39 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts
21:18:39 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts
21:18:40 INFO - Running setup.py install for mozfile
21:18:40 INFO - Running setup.py install for mozhttpd
21:18:40 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts
21:18:40 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts
21:18:40 INFO - Running setup.py install for mozinfo
21:18:41 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts
21:18:41 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts
21:18:41 INFO - Running setup.py install for mozInstall
21:18:41 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts
21:18:41 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts
21:18:41 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts
21:18:41 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts
21:18:41 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts
21:18:41 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts
21:18:41 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts
21:18:41 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts
21:18:41 INFO - Running setup.py install for mozleak
21:18:42 INFO - Running setup.py install for mozlog
21:18:42 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts
21:18:42 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts
21:18:42 INFO - Running setup.py install for moznetwork
21:18:43 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts
21:18:43 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts
21:18:43 INFO - Running setup.py install for mozprocess
21:18:43 INFO - Running setup.py install for mozprofile
21:18:44 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts
21:18:44 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts
21:18:44 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts
21:18:44 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts
21:18:44 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts
21:18:44 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts
21:18:44 INFO - Running setup.py install for mozrunner
21:18:44 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts
21:18:44 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts
21:18:45 INFO - Running setup.py install for mozscreenshot
21:18:45 INFO - Running setup.py install for moztest
21:18:45 INFO - Running setup.py install for mozversion
21:18:46 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts
21:18:46 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts
21:18:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion
21:18:46 INFO - Cleaning up...
21:18:46 INFO - Return code: 0
21:18:46 INFO - Installing None into virtualenv C:\slave\test\build/venv
21:18:46 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')]}
21:18:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
21:18:46 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')]}
21:18:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
21:18:46 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 0x01968640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019A2E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018E2B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019BCE90>, '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 0x013BA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A28520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-055', 'USERDOMAIN': 'T-W732-IX-055', '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-055', '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
21:18:46 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
21:18:46 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
21:18:46 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
21:18:46 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
21:18:46 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
21:18:46 INFO - 'COMPUTERNAME': 'T-W732-IX-055',
21:18:46 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
21:18:46 INFO - 'CYGWINBASE': 'C:\\cygwin',
21:18:46 INFO - 'DCLOCATION': 'SCL3',
21:18:46 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
21:18:46 INFO - 'FP_NO_HOST_CHECK': 'NO',
21:18:46 INFO - 'HOMEDRIVE': 'C:',
21:18:46 INFO - 'HOMEPATH': '\\Users\\cltbld',
21:18:46 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
21:18:46 INFO - 'KTS_VERSION': '1.19c',
21:18:46 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
21:18:46 INFO - 'LOGONSERVER': '\\\\T-W732-IX-055',
21:18:46 INFO - 'MONDIR': 'C:\\Monitor_config\\',
21:18:46 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
21:18:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
21:18:46 INFO - 'MOZ_NO_REMOTE': '1',
21:18:46 INFO - 'NO_EM_RESTART': '1',
21:18:46 INFO - 'NUMBER_OF_PROCESSORS': '8',
21:18:46 INFO - 'OS': 'Windows_NT',
21:18:46 INFO - 'OURDRIVE': 'C:',
21:18:46 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;',
21:18:46 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
21:18:46 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
21:18:46 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
21:18:46 INFO - 'PROCESSOR_LEVEL': '6',
21:18:46 INFO - 'PROCESSOR_REVISION': '1e05',
21:18:46 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
21:18:46 INFO - 'PROGRAMFILES': 'C:\\Program Files',
21:18:46 INFO - 'PROMPT': '$P$G',
21:18:46 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
21:18:46 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
21:18:46 INFO - 'PUBLIC': 'C:\\Users\\Public',
21:18:46 INFO - 'PWD': 'C:\\slave\\test',
21:18:46 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
21:18:46 INFO - 'SLAVEDIR': 'C:\\slave\\',
21:18:46 INFO - 'SYSTEMDRIVE': 'C:',
21:18:46 INFO - 'SYSTEMROOT': 'C:\\windows',
21:18:46 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:18:46 INFO - 'TEST1': 'testie',
21:18:46 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:18:46 INFO - 'USERDOMAIN': 'T-W732-IX-055',
21:18:46 INFO - 'USERNAME': 'cltbld',
21:18:46 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
21:18:46 INFO - 'WINDIR': 'C:\\windows',
21:18:46 INFO - 'WINDOWS_TRACING_FLAGS': '3',
21:18:46 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
21:18:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
21:18:46 INFO - Ignoring indexes: https://pypi.python.org/simple/
21:18:46 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
21:18:46 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lhyuev-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
21:18:46 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))
21:18:46 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
21:18:46 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-eqnwib-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
21:18:47 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))
21:18:47 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
21:18:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ftgxyt-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
21:18:47 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))
21:18:47 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
21:18:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-s2upkv-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
21:18:47 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))
21:18:47 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
21:18:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-djowhm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
21:18:47 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))
21:18:47 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
21:18:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ctowxi-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
21:18:47 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))
21:18:47 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
21:18:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-6ivjw_-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
21:18:47 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))
21:18:47 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
21:18:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wrfarr-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
21:18:47 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))
21:18:47 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
21:18:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cjeo5z-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
21:18:48 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))
21:18:48 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
21:18:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qh6wd2-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
21:18:48 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))
21:18:48 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
21:18:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dgip5u-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
21:18:48 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))
21:18:48 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
21:18:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-05bey8-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
21:18:48 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))
21:18:48 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
21:18:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-nwdx5r-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
21:18:48 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))
21:18:48 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
21:18:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vwqkih-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
21:18:48 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))
21:18:48 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
21:18:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-y8csrz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
21:18:48 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))
21:18:48 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
21:18:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wwsg8e-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
21:18:49 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))
21:18:49 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
21:18:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-4wtjwv-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
21:18:49 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))
21:18:49 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))
21:18:49 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))
21:18:49 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))
21:18:49 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))
21:18:49 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))
21:18:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10))
21:18:49 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
21:18:49 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
21:18:50 INFO - Downloading blessings-1.6.tar.gz
21:18:50 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings
21:18:50 INFO - Installing collected packages: blessings
21:18:50 INFO - Running setup.py install for blessings
21:18:54 INFO - Successfully installed blessings
21:18:54 INFO - Cleaning up...
21:18:57 INFO - Return code: 0
21:18:57 INFO - Done creating virtualenv C:\slave\test\build/venv.
21:18:57 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
21:18:57 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
21:18:57 INFO - Reading from file tmpfile_stdout
21:18:57 INFO - Using _rmtree_windows ...
21:18:57 INFO - Using _rmtree_windows ...
21:18:57 INFO - Current package versions:
21:18:57 INFO - blessings == 1.6
21:18:57 INFO - blobuploader == 1.2.4
21:18:57 INFO - docopt == 0.6.1
21:18:57 INFO - functools32 == 3.2.3-2
21:18:57 INFO - jsonschema == 2.5.1
21:18:57 INFO - manifestparser == 1.1
21:18:57 INFO - mozInstall == 1.12
21:18:57 INFO - mozcrash == 0.17
21:18:57 INFO - mozdebug == 0.1
21:18:57 INFO - mozdevice == 0.48
21:18:57 INFO - mozfile == 1.2
21:18:57 INFO - mozhttpd == 0.7
21:18:57 INFO - mozinfo == 0.9
21:18:57 INFO - mozleak == 0.1
21:18:57 INFO - mozlog == 3.4
21:18:57 INFO - moznetwork == 0.27
21:18:57 INFO - mozprocess == 0.23
21:18:57 INFO - mozprofile == 0.28
21:18:57 INFO - mozrunner == 6.12
21:18:57 INFO - mozscreenshot == 0.1
21:18:57 INFO - mozsystemmonitor == 0.3
21:18:57 INFO - moztest == 0.7
21:18:57 INFO - mozversion == 1.4
21:18:57 INFO - psutil == 3.1.1
21:18:57 INFO - requests == 1.2.3
21:18:57 INFO - Installing None into virtualenv C:\slave\test\build/venv
21:18:57 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')]}
21:18:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
21:18:57 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')]}
21:18:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
21:18:57 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 0x01968640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019A2E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018E2B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019BCE90>, '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 0x013BA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A28520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-055', 'USERDOMAIN': 'T-W732-IX-055', '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-055', '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
21:18:57 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
21:18:57 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
21:18:57 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
21:18:57 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
21:18:57 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
21:18:57 INFO - 'COMPUTERNAME': 'T-W732-IX-055',
21:18:57 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
21:18:57 INFO - 'CYGWINBASE': 'C:\\cygwin',
21:18:57 INFO - 'DCLOCATION': 'SCL3',
21:18:57 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
21:18:57 INFO - 'FP_NO_HOST_CHECK': 'NO',
21:18:57 INFO - 'HOMEDRIVE': 'C:',
21:18:57 INFO - 'HOMEPATH': '\\Users\\cltbld',
21:18:57 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
21:18:57 INFO - 'KTS_VERSION': '1.19c',
21:18:57 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
21:18:57 INFO - 'LOGONSERVER': '\\\\T-W732-IX-055',
21:18:57 INFO - 'MONDIR': 'C:\\Monitor_config\\',
21:18:57 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
21:18:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
21:18:57 INFO - 'MOZ_NO_REMOTE': '1',
21:18:57 INFO - 'NO_EM_RESTART': '1',
21:18:57 INFO - 'NUMBER_OF_PROCESSORS': '8',
21:18:57 INFO - 'OS': 'Windows_NT',
21:18:57 INFO - 'OURDRIVE': 'C:',
21:18:57 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;',
21:18:57 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
21:18:57 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
21:18:57 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
21:18:57 INFO - 'PROCESSOR_LEVEL': '6',
21:18:57 INFO - 'PROCESSOR_REVISION': '1e05',
21:18:57 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
21:18:57 INFO - 'PROGRAMFILES': 'C:\\Program Files',
21:18:57 INFO - 'PROMPT': '$P$G',
21:18:57 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
21:18:57 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
21:18:57 INFO - 'PUBLIC': 'C:\\Users\\Public',
21:18:57 INFO - 'PWD': 'C:\\slave\\test',
21:18:57 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
21:18:57 INFO - 'SLAVEDIR': 'C:\\slave\\',
21:18:57 INFO - 'SYSTEMDRIVE': 'C:',
21:18:57 INFO - 'SYSTEMROOT': 'C:\\windows',
21:18:57 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:18:57 INFO - 'TEST1': 'testie',
21:18:57 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:18:57 INFO - 'USERDOMAIN': 'T-W732-IX-055',
21:18:57 INFO - 'USERNAME': 'cltbld',
21:18:57 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
21:18:57 INFO - 'WINDIR': 'C:\\windows',
21:18:57 INFO - 'WINDOWS_TRACING_FLAGS': '3',
21:18:57 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
21:18:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
21:18:58 INFO - Ignoring indexes: https://pypi.python.org/simple/
21:18:58 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))
21:18:58 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))
21:18:58 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))
21:18:58 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))
21:18:58 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))
21:18:58 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))
21:18:58 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))
21:18:58 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))
21:18:58 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))
21:18:58 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))
21:18:58 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))
21:18:58 INFO - Cleaning up...
21:18:58 INFO - Return code: 0
21:18:58 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv
21:18:58 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')]}
21:18:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
21:18:58 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')]}
21:18:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
21:18:58 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 0x01968640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019A2E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018E2B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019BCE90>, '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 0x013BA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A28520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-055', 'USERDOMAIN': 'T-W732-IX-055', '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-055', '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
21:18:58 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
21:18:58 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
21:18:58 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
21:18:58 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
21:18:58 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
21:18:58 INFO - 'COMPUTERNAME': 'T-W732-IX-055',
21:18:58 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
21:18:58 INFO - 'CYGWINBASE': 'C:\\cygwin',
21:18:58 INFO - 'DCLOCATION': 'SCL3',
21:18:58 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
21:18:58 INFO - 'FP_NO_HOST_CHECK': 'NO',
21:18:58 INFO - 'HOMEDRIVE': 'C:',
21:18:58 INFO - 'HOMEPATH': '\\Users\\cltbld',
21:18:58 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
21:18:58 INFO - 'KTS_VERSION': '1.19c',
21:18:58 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
21:18:58 INFO - 'LOGONSERVER': '\\\\T-W732-IX-055',
21:18:58 INFO - 'MONDIR': 'C:\\Monitor_config\\',
21:18:58 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
21:18:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
21:18:58 INFO - 'MOZ_NO_REMOTE': '1',
21:18:58 INFO - 'NO_EM_RESTART': '1',
21:18:58 INFO - 'NUMBER_OF_PROCESSORS': '8',
21:18:58 INFO - 'OS': 'Windows_NT',
21:18:58 INFO - 'OURDRIVE': 'C:',
21:18:58 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;',
21:18:58 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
21:18:58 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
21:18:58 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
21:18:58 INFO - 'PROCESSOR_LEVEL': '6',
21:18:58 INFO - 'PROCESSOR_REVISION': '1e05',
21:18:58 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
21:18:58 INFO - 'PROGRAMFILES': 'C:\\Program Files',
21:18:58 INFO - 'PROMPT': '$P$G',
21:18:58 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
21:18:58 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
21:18:58 INFO - 'PUBLIC': 'C:\\Users\\Public',
21:18:58 INFO - 'PWD': 'C:\\slave\\test',
21:18:58 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
21:18:58 INFO - 'SLAVEDIR': 'C:\\slave\\',
21:18:58 INFO - 'SYSTEMDRIVE': 'C:',
21:18:58 INFO - 'SYSTEMROOT': 'C:\\windows',
21:18:58 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:18:58 INFO - 'TEST1': 'testie',
21:18:58 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:18:58 INFO - 'USERDOMAIN': 'T-W732-IX-055',
21:18:58 INFO - 'USERNAME': 'cltbld',
21:18:58 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
21:18:58 INFO - 'WINDIR': 'C:\\windows',
21:18:58 INFO - 'WINDOWS_TRACING_FLAGS': '3',
21:18:58 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
21:18:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
21:18:58 INFO - Ignoring indexes: https://pypi.python.org/simple/
21:18:58 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages
21:18:58 INFO - Cleaning up...
21:18:58 INFO - Return code: 0
21:18:58 INFO - Running post-action listener: _resource_record_post_action
21:18:58 INFO - Running post-action listener: _start_resource_monitoring
21:18:58 INFO - Starting resource monitoring.
21:18:58 INFO - [mozharness: 2016-10-15 04:18:58.633000Z] Finished create-virtualenv step (success)
21:18:58 INFO - [mozharness: 2016-10-15 04:18:58.634000Z] Running install step.
21:18:58 INFO - Running pre-action listener: _resource_record_pre_action
21:18:58 INFO - Running main action method: install
21:18:58 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
21:18:58 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
21:18:58 INFO - Reading from file tmpfile_stdout
21:18:58 INFO - Using _rmtree_windows ...
21:18:58 INFO - Using _rmtree_windows ...
21:18:58 INFO - Detecting whether we're running mozinstall >=1.0...
21:18:58 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h']
21:18:58 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h
21:18:59 INFO - Reading from file tmpfile_stdout
21:18:59 INFO - Output received:
21:18:59 INFO - Usage: mozinstall-script.py [options] installer
21:18:59 INFO - Options:
21:18:59 INFO - -h, --help show this help message and exit
21:18:59 INFO - -d DEST, --destination=DEST
21:18:59 INFO - Directory to install application into. [default:
21:18:59 INFO - "C:\slave\test"]
21:18:59 INFO - --app=APP Application being installed. [default: firefox]
21:18:59 INFO - Using _rmtree_windows ...
21:18:59 INFO - Using _rmtree_windows ...
21:18:59 INFO - mkdir: C:\slave\test\build\application
21:18:59 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']
21:18:59 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
21:19:03 INFO - Reading from file tmpfile_stdout
21:19:03 INFO - Output received:
21:19:03 INFO - C:\slave\test\build\application\firefox\firefox.exe
21:19:03 INFO - Using _rmtree_windows ...
21:19:03 INFO - Using _rmtree_windows ...
21:19:03 INFO - Running post-action listener: _resource_record_post_action
21:19:03 INFO - [mozharness: 2016-10-15 04:19:03.024000Z] Finished install step (success)
21:19:03 INFO - [mozharness: 2016-10-15 04:19:03.024000Z] Running run-tests step.
21:19:03 INFO - Running pre-action listener: _resource_record_pre_action
21:19:03 INFO - Running main action method: run_tests
21:19:03 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version']
21:19:03 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version
21:19:03 INFO - Python 2.7.3
21:19:03 INFO - Return code: 0
21:19:03 INFO - grabbing minidump binary from tooltool
21:19:03 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')]}
21:19:03 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 0x019BCE90>, '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 0x013BA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A28520>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1
21:19:03 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
21:19:03 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
21:19:03 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
21:19:03 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'...
21:19:03 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmpsykqbk
21:19:03 INFO - INFO - File integrity verified, renaming tmpsykqbk to win32-minidump_stackwalk.exe
21:19:03 INFO - Return code: 0
21:19:03 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755
21:19:03 INFO - mkdir: C:\slave\test\build\blobber_upload_dir
21:19:03 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir
21:19:03 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe
21:19:03 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir
21:19:03 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/1476495004/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-055', '--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
21:19:03 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/1476495004/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-055 --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
21:19:03 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
21:19:03 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
21:19:03 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
21:19:03 INFO - 'COMPUTERNAME': 'T-W732-IX-055',
21:19:03 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
21:19:03 INFO - 'CYGWINBASE': 'C:\\cygwin',
21:19:03 INFO - 'DCLOCATION': 'SCL3',
21:19:03 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
21:19:03 INFO - 'FP_NO_HOST_CHECK': 'NO',
21:19:03 INFO - 'HOMEDRIVE': 'C:',
21:19:03 INFO - 'HOMEPATH': '\\Users\\cltbld',
21:19:03 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
21:19:03 INFO - 'KTS_VERSION': '1.19c',
21:19:03 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
21:19:03 INFO - 'LOGONSERVER': '\\\\T-W732-IX-055',
21:19:03 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir',
21:19:03 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe',
21:19:03 INFO - 'MONDIR': 'C:\\Monitor_config\\',
21:19:03 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
21:19:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
21:19:03 INFO - 'MOZ_NO_REMOTE': '1',
21:19:03 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir',
21:19:03 INFO - 'NO_EM_RESTART': '1',
21:19:03 INFO - 'NUMBER_OF_PROCESSORS': '8',
21:19:03 INFO - 'OS': 'Windows_NT',
21:19:03 INFO - 'OURDRIVE': 'C:',
21:19:03 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;',
21:19:03 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
21:19:03 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
21:19:03 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
21:19:03 INFO - 'PROCESSOR_LEVEL': '6',
21:19:03 INFO - 'PROCESSOR_REVISION': '1e05',
21:19:03 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
21:19:03 INFO - 'PROGRAMFILES': 'C:\\Program Files',
21:19:03 INFO - 'PROMPT': '$P$G',
21:19:03 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
21:19:03 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
21:19:03 INFO - 'PUBLIC': 'C:\\Users\\Public',
21:19:03 INFO - 'PWD': 'C:\\slave\\test',
21:19:03 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos',
21:19:03 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
21:19:03 INFO - 'SLAVEDIR': 'C:\\slave\\',
21:19:03 INFO - 'SYSTEMDRIVE': 'C:',
21:19:03 INFO - 'SYSTEMROOT': 'C:\\windows',
21:19:03 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:19:03 INFO - 'TEST1': 'testie',
21:19:03 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:19:03 INFO - 'USERDOMAIN': 'T-W732-IX-055',
21:19:03 INFO - 'USERNAME': 'cltbld',
21:19:03 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
21:19:03 INFO - 'WINDIR': 'C:\\windows',
21:19:03 INFO - 'WINDOWS_TRACING_FLAGS': '3',
21:19:03 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
21:19:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
21:19:03 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/1476495004/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-055', '--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
21:19:04 INFO - mozversion application_buildid: 20161014183004
21:19:04 INFO - mozversion application_changeset: 45f9fc18cf3dab70ba8d1982f40fea9246ae60b9
21:19:04 INFO - mozversion application_display_name: Nightly
21:19:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
21:19:04 INFO - mozversion application_name: Firefox
21:19:04 INFO - mozversion application_remotingname: firefox
21:19:04 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team
21:19:04 INFO - mozversion application_vendor: Mozilla
21:19:04 INFO - mozversion application_version: 52.0a1
21:19:04 INFO - mozversion platform_buildid: 20161014183004
21:19:04 INFO - mozversion platform_changeset: 45f9fc18cf3dab70ba8d1982f40fea9246ae60b9
21:19:04 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team
21:19:04 INFO - mozversion platform_version: 52.0a1
21:19:04 INFO - using testdate: 1476505144
21:19:04 INFO - actual date: 1476505144
21:19:04 INFO - starting webserver on 'localhost:49891'
21:19:04 INFO - SUITE-START | Running 2 tests
21:19:04 INFO - TEST-START | dromaeo_css
21:19:04 INFO - operating with platform_type : w7_
21:19:04 INFO - Initialising browser for dromaeo_css test...
21:19:05 INFO - TEST-INFO | started process 3008 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpytnsrx\profile http://localhost:49891/getInfo.html)
21:19:13 INFO - PROCESS | 3008 | __metrics Screen width/height:1600/1200
21:19:13 INFO - PROCESS | 3008 | colorDepth:24
21:19:13 INFO - PROCESS | 3008 | Browser inner width/height: 1010/674
21:19:13 INFO - PROCESS | 3008 | __metrics
21:19:14 INFO - PROCESS | 3008 | 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"
21:19:14 INFO - PROCESS | 3008 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
21:19:15 INFO - TEST-INFO | 3008: exit 0
21:19:15 INFO - Browser initialized.
21:19:15 INFO - Running cycle 1/1 for dromaeo_css test...
21:19:15 INFO - TEST-INFO | started process 2564 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpytnsrx\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1)
21:19:16 INFO - PROCESS | 2564 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0
21:19:27 INFO - PROCESS | 2564 | RSS: Main: 122929152
21:19:27 INFO - PROCESS | 2564 |
21:20:18 INFO - PROCESS | 2564 | 1476505218982 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]
21:22:27 INFO - PROCESS | 2564 | Cycle 1(1): loaded http://localhost:49891/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49891/tests/dromaeo/cssquery-ext.html)
21:22:30 INFO - PROCESS | 2564 | RSS: Main: 367812608
21:22:30 INFO - PROCESS | 2564 |
21:25:32 INFO - PROCESS | 2564 | Cycle 1(1): loaded http://localhost:49891/tests/dromaeo/cssquery-ext.html (next: http://localhost:49891/tests/dromaeo/cssquery-jquery.html)
21:25:33 INFO - PROCESS | 2564 | RSS: Main: 197066752
21:25:33 INFO - PROCESS | 2564 |
21:28:35 INFO - PROCESS | 2564 | Cycle 1(1): loaded http://localhost:49891/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49891/tests/dromaeo/cssquery-mootools.html)
21:28:36 INFO - PROCESS | 2564 | RSS: Main: 157118464
21:28:36 INFO - PROCESS | 2564 |
21:31:38 INFO - PROCESS | 2564 | Cycle 1(1): loaded http://localhost:49891/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49891/tests/dromaeo/cssquery-prototype.html)
21:31:38 INFO - PROCESS | 2564 | RSS: Main: 151367680
21:31:38 INFO - PROCESS | 2564 |
21:34:41 INFO - PROCESS | 2564 | Cycle 1(1): loaded http://localhost:49891/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49891/tests/dromaeo/cssquery-yui.html)
21:34:42 INFO - PROCESS | 2564 | RSS: Main: 131670016
21:34:42 INFO - PROCESS | 2564 |
21:37:44 INFO - PROCESS | 2564 | Cycle 1(1): loaded http://localhost:49891/tests/dromaeo/cssquery-yui.html (next: http://localhost:49891/tests/dromaeo/cssquery-dojo.html)
21:37:44 INFO - PROCESS | 2564 | RSS: Main: 183320576
21:37:44 INFO - PROCESS | 2564 |
21:37:44 INFO - PROCESS | 2564 | __start_tp_report
21:37:44 INFO - PROCESS | 2564 | _x_x_mozilla_page_load
21:37:44 INFO - PROCESS | 2564 | _x_x_mozilla_page_load_details
21:37:44 INFO - PROCESS | 2564 | |i|pagename|runs|
21:37:44 INFO - PROCESS | 2564 | |0;dojo.html;2737.3156710822295;2755.5591105423136;2759.5998580205865;2763.9723602763966;2770.9030183943564;4688.249880019193;4821.180399332113;4911.607071434286;4915.188993816025;4928.186849169882;4784.760761961897;4823.13183626947;4827.903441931159;4849.393825771778;4935.457099719028;4191.979040104795;4264.402983582301;4288.421063156473;4315.417418648473;4316.309390497521;4080.2655522006025;4197.286461301571;4276.337167739005;4340.696151269412;4350.54319296474;4252.936205956913;4262.680298977573;4325;4358.716683415583;4361;4187.790610469464;4255.212785634668;4327.891802704926;4342.522322544518;4354.063876266618;4079.2453396121514;4165.604267594574;4309;4312.05134870328;4319.43847299849;4682.484926658065;4759.809607615691;4763.261694437368;4868.878278043042;4880.048390563841;4796.472388037313;4960.156773348539;5005.57452616528;5056.418511871145;5090.949090509084;5087.211482220225;5185.974070129625;5189.584833213334;5196.168613021899;5211.348581427322;4612.215923293048;4665.486796452387;4738.076075165379;4768.260919557474;4784.114938736345;4969.130402179604;5084.872878178001;5121.129408000649;5216.921746173811;5295;4894;4934.185859333242;4985.950140498621;5028.346314979102;5064.721440320818;4796.472388037313;4994.750262486861;5107.923381149286;5112.693238405707;5146.768395422215;4872.439669437989;5004.2493625956395;5116.744162791845;5117.897642047213;5123.795048198031;4923.975380123076;4942.851714448572;5000.049990501793;5035.169197082514;5138.2806407103035;3353.228757385815;3395.185155562647;3411.880584179542;3418.7265018798435;3447.5345828313357;3042.7717921156;3078.7229149376662;3154.3218208084913;3159.794613350125;3161.0674850919036;4506.369108324837;4655.720656760605;4675.719456832601;4785.114753770564;4785.49752276013;5014.84955451337;5015.724135172601;5114.795408183631;5123.666961647481;5128.692278463304;4404.647628189737;4534.7052441591195;4604.861854144381;4646.86059418218;4684.718916864931;4110.260153172458;4192.727472714264;4208.936865947013;4210.347396153601;4218.662506999494;5175.576716403019;5297.682139071668;5325.0148722486465;5326.760295786699;5328.333958255218;4848.660593758403;4856.368672072608;4912.410510738591;5025.673331233458;5134.204198349113;5023.271625614192;5058.671186372874;5230.529252367305;5280.7359632019015;5339.73301334924;4921.458639549575;5002.624803139852;5024.6231532634465;5073.670211436245;5133.948660513347;5093.23601459784;5114.386273647037;5170.508801663836;5217.321748172713;5231.712255825966;4894.92657610129;4963.8262660806695;4965.528274813887;4988.12707776145;4993.900121997467;4944.851654450372;4976.776045077908;4981.42713587942;4988.55103040728;4989.351384320015;4838.322634831196;4874.6344024197615;4877.634177436636;4883.511648835088;4885.877853053702;4844.958333958216;4985;4992.301077849177;4996.3504744383;5007.449180590059;4731.6214702823;4830.3237546744185;4926.4088309403105;4926.458089610069;4936.975315123402
21:37:44 INFO - PROCESS | 2564 | |1;ext.html;524.9711265880376;569.3907518954719;583.2913010692008;588.8351261646735;598.709625831472;28759;30211.24471888201;40568.79715601418;40699;41237.38143927836;41713.95715107124;41984.58015419845;42348.78825605875;42418;42429;8115.959420202891;8395.874061889092;8460.323174146053;8523.829523409528;8580.828383432328;8351.290140338078;8419.326453883705;8487.490750554956;8493.95753021234;8514.361422893277;8025.43821932462;8051;8279;8295.460795048333;8460.619272132768;8477.74566762998;8489.938757655293;8500.872486912702;8501.659933602648;8524.232819046285;8458.281046111088;8524.403291769579;8530.317574594017;8531.274841638467;8532.14678532148;38689.80655096735;38821.61178388208;38899.22201555981;39025.63410280655;39118.217635647125;3134.514150306706;3153.5087281047945;3210.839458027089;3264.689854463822;3693.725664645693;30486.664833654242;30699.38601227963;31091.84454077715;31644.36711265785;31960.520592191137;3202.1994501374656;3260.8695652173883;3281.819499927503;3282.376348493802;3309.8676052957853;317544;322667;342783;343862;345520;49285.26072108921;50535.24197137046;50690;50721.74639126855;50731.49268507341;53623.46376536185;53625.463745362824;56756.71621641948;56996;57058.7147064262;14246.287685615678;14510.419583216764;14540.200288983995;14555;14613.853861461463;11114.777704446027;11213;11237;11276.154288428237;11277.041451476553;17877;17935.73096403555;17985;18020.459386218434;18210.54473638143;7031.753888613874;7211.567305961659;7228.530145540523;7257.201707812136;7259.637018149071;769.5575044349409;897.3852910756153;900.4372267332917;939.4504215034282;981.249344251648;26851.86574067117;27336.179914602595;27386.45227095487;27435.588466173023;28350.432991340058;881.3698205782896;904.624580798961;941.1764705882352;967.7193613852045;969.1374676537887;14064.507742228972;14386;14483.348249328596;14533.636659083608;14768.113913165245;494369;500377;505655;506079;508284;47356.28965565519;47357.76321118372;49436.258456123185;49990.25014624783;50497.747511262205;1091.8089334366455;1140.1164097824253;1144.6165534545853;1151.1481503687212;1154.8498695169574;26438.339041523348;26605.7339426611;27409;27749.583756244076;27801.58297625497;43053.56946430496;43860.34209486796;44967.55032449633;44982;44988.55011449844;438401;443335.7833210813;444035;447886;449521;31657;31826.522602160523;37948.05129871665;38680.6131938677;39119.21761564696;40772.362800688956;42657;42879.142417150855;43409;44092.77953610211;36540;36585.63414365822;36884.81557592195;38430;38979.610203897595;37299.81350093232;37858.43212351762;38175.42736859005;38561.614383856926;39845.20309593734;34861.30277394387;35155.824220878734;35262.47106293459;35639.465408019416;35788.8210558956;31571.842140789147;32832.343353132324;33568;34160.48759268663;34644.653553464144;23661.408464788517;25311.240662780147;25383;25490;25976
21:37:44 INFO - PROCESS | 2564 | |2;jquery.html;299.48189631936765;323.0550639379814;323.27908763457486;330.64125423915044;333.7480202447152;13223.603291901247;18043.729344059833;18511.537211569685;18722.906385468086;19174.808251917475;17348.045857477835;17703.46889593311;17900.64198716025;18149.183286752093;18198.999055051965;4963.950360496385;5185.248139019849;5590.077637189859;5686.943130568703;5691.117876729093;6160.1683772690785;6354.523410744189;6419.518536109787;6429.549931504773;6434.453071488929;6147.538934579902;6298.118263443122;6336.176297081373;6343.0802533632595;6360.586561873487;6282.905756413657;6315.526335524831;6327.905081423783;6335.873282534323;6348.873022539569;5247.763850626693;5412.107002344608;5443.237946687467;5446.264754258164;5454.672719636795;22109.33671989843;22450.663240051414;23296;23324;23397.41506462335;1931.2468137426415;1959.794221606725;2031.2484380779206;2740.0957683964243;2760.9447811043665;13387.464501419932;13993.930030349784;14377.281135943265;14612.123272603678;15025.849741502554;1761.2162587648502;1803.3409263477631;1823.2251293200336;1862.3854128137682;1875.0530981854213;81906.9999999994;83296;84045.15954840435;84100;84499.5775021127;9015.63937442508;9177;9243.630254789734;9347.112024357675;9611.951940240255;11235.71910702239;11615;11753.118516111326;12192.390380481118;12258.754824903452;7209.35115839577;7269.818254543678;7298.379637730853;7305.525140865827;7408.370288525536;7157.320054594805;7268.927310726931;7301.488895777351;7302.853942921111;7458.589777562178;3728.55257369112;3747.4004159334377;3807.6763475104926;3865.439511270906;3892.3188442022533;1623.5454072859618;1635.8364163583785;1663.25984936702;1666.8833181677167;1676.5305714399988;832.8958880139983;840.844443777903;866.3459088388353;893.9138946180421;911.4349103555705;18251.72622410665;18339.816601834078;18509.51923846089;18819.71770423445;19478.12348444295;815.9020917489939;822.1244374741001;826.9669213231524;868.1015149320423;931.0549791961165;9722.416655000723;9729.854052189223;10067.69796906094;10095.798084038279;10203.846942295871;431174;432001;444189;451499;451559;99505.27638692157;100918.99081009242;101809.49095254476;102797;102817.48591256996;845.6913226672343;877.4384393987852;880.212210071982;882.5587206396801;883.5140672630027;14014.719705605627;16279.418527923837;16833.831661683227;17675.734863976795;17892.28430862751;44791;45820.54179458163;45958.080838382375;46075.30887036764;46369;61668.38331616626;63734.68132659307;64081.35918640754;64368.356316436235;64685.67657161684;7274.6726397313305;7359.080114985627;7360.411167106726;7374.926250737639;7478.887816682645;8867;12196.329201893626;12696.301703406401;12723.872761272269;13278.203307801563;10595.311304765166;10674.62638807638;10726.946365268124;11007.174461915229;11061.778764424827;10079;10210.387376757419;10493.632722854663;10603.787924241626;10861.837072444077;10874.184436167161;10895.182861285593;10927.56289748433;11066.612668556561;11180.888191117985;7057.094584462269;7197.172325182671;7320.9267907320245;7352.154502232312;7397.445191610758;9969.950150249202;10003.899961000589;10567.418791966516;10756.247062705535;10793.62222322215
21:37:44 INFO - PROCESS | 2564 | |3;mootools.html;1148.9999999999998;1167.0371943146902;1264.355178858782;1483.925803709815;1522.3758259113765;6877.037214789933;7405.259474052592;7405.444591655621;7407.925920740791;7408.6666100025495;6407.903881441771;6571.047198156264;6949.061876646651;7082.327178918007;7250.52871563348;1937.1282922684777;1969.8424126069951;1971.4972681966078;1971.541059615881;1973.2698901406427;1848.6117915237817;1887.254534458888;1924.0572119661406;1924.5188702824294;1955.325412732605;1662.6508433229037;1678.4964510646819;1902.800205978373;1915.5594213330942;1958.3635318521538;1928.1227041695995;1940.621578792129;1940.6603844327328;1941.553442708171;1941.6019715958196;1846.621442604263;1864.7482589850467;1868.5141863115552;1885.2176346816054;1894.0908363985363;6949.3745562899585;7016.263292354281;7120.821979450503;7130;7130.358267755927;4218.282891908383;4322.222000039991;4326.8269269229195;4328.4156638853665;4332.653387728974;2760.3651160233057;2874.9712502874913;2882.7837912156674;2918.8540572971265;2921.182069020678;906.7622695021314;907.020417948614;911.2436677557612;924.2236521322121;947.9241660667199;9113.134252246025;9191.264698824078;9195.356325057248;9202.125798049176;9221.17009469151;3728.496652951871;3836.693064554829;3846.076941534068;3848.99926019237;3871.787051712183;7076;7118.893216601755;7465.477416580896;7475.289847464483;7497.10034795828;4158.147579746144;4216.198922204771;4280.72175308604;4282.143571285756;4286.035641980529;3867.284552357823;4044.3933409988736;4045.332520134145;4049.655779258796;4073.9592604073578;2897.1357409713573;2952.9114126576237;2953.4536110819568;3026.379592183597;3030.575719399286;926.4395041000232;939.0093451408829;941.091846368258;941.2799208605422;947.6398968392105;733.6698485681464;764.5527366490666;774.4269240761796;775.2325198053999;853.0744142605342;4587.655925805578;4879.536444037811;4902.142125128088;4944.826931057396;5029.119904016782;333.2751265996448;338.6664135826209;340.787008119925;342.7104097037999;344.08645047399193;2996.5355369917697;3006.9849650751607;3088.9382212356077;3128.7340576051292;3185.108169712484;8337.916620833836;8559.486430814171;8603.784905377415;8612.956935215283;8618.482891026559;8579;8607.870881936777;8639.179277968582;8664.740057798275;8667.956660216785;6384.52116091295;6746.831329216809;6837.658117094125;6840.281770414035;6844.041834143124;4621.861344159681;4857.417109946829;4862.756862156949;4906.2640603909695;5000.499950005116;7976.92023079785;8102.797430064295;8123.471974321887;8134;8158.224968627971;8010.599470026359;8196.836063278583;8212.055613604512;8262.752117436487;8274.710385136492;5549.778008879761;5815.709214539172;5850.5904586678525;5899.38056504078;5909.290885093645;6652.73389064432;6736.629485378351;7052.0479735235085;7070.6111163884425;7075.150981882001;5696.430356964436;5930.851728706817;6273.404026617464;6355.682215889279;6377.23473183221;5836;6149.938500615115;6271.4669253114;6293.433590976834;6352.745890164341;5763.36602973681;5807.0998995155815;5810.302763668386;5837.124431335334;5842.737076831638;4343.652507799432;4379.080393117481;4416.933745993877;4441.955580444154;4519.932201016922;4480.14877173336;4480.61914737251;4497.595216430537;4522.118186953512;4609.216433206296
21:37:44 INFO - PROCESS | 2564 | |4;prototype.html;257.319390212887;266.27307450659697;267.7015128132133;269.1575369094734;269.236713916048;4975.25371194321;5071.797128114871;5124.180131179012;5124.743762811859;5139.486051394863;4943.629227807911;5032.295478632995;5038.420581633113;5046.419661738901;5048.1165795985735;3693.8944801463176;3724.944125838115;3725.1245957200035;3733.4586484959664;3733.9439908401328;3585.8744943926968;3656.213914008488;3679.926401471969;3693.8891833245048;3708.740388172829;3587.65917237862;3639.1084184374863;3644.9817750911207;3645.6172101929315;3661.304352173092;3666.6883314918264;3696.2053158570766;3717.2379662169324;3718.9070273243115;3720.18156005681;3190.170555655523;3196.0252123102446;3212.100611828692;3212.3254116635535;3222.822744749038;4922.852314430573;4948.158813001798;4957.454679985199;4961.355023846912;4966.801327946878;4210.0948296116485;4323.697341186087;4332;4349.304111342201;4384.276594361926;5234;5252.159654455307;5253.08071087562;5255.842324730264;5269.209618557209;3555.466679997995;3564.8930532084078;3580.230250496156;3581.08682286018;3582.8566857325677;5492.697901615409;5509.228707980886;5526.281583394173;5527.170924361338;5550.666959982374;3559.5728512578658;3653.8721144759807;3659.70722342212;3664.6518580734787;3671.724620653462;3699.7595156314756;3718.5723641781;3724.2309902796496;3726.2920045191327;3730.7388482806487;4317.266064768997;4332.328489084197;4346.195953748566;4346.304591265382;4351.347297905276;5317.447625139877;5333.866653333697;5340.305760251143;5345.572354211654;5346.4386239445075;1269.225772278909;1279.1365828066018;1286.498265676387;1287.1247551664771;1291.2381694800113;798.4490701416005;815.4495715392087;816.4407380944073;826.98346033079;827.3009307135461;4886.338644860764;5002.499750025041;5016.724080175552;5038.48075962019;5042.4957504250015;5417.485338892799;5476.780928762884;5505.21986396867;5513.862153446196;5518.668879867221;4971.533397647702;4971.776270067856;4999.700017998932;5024.824131155392;5036.11867923112;2765.8340499569686;2838.4606924684244;2859.6139521164378;2897.7102289770855;2906.3751293471582;5646.689432081275;5721.76981948883;5723.971380143073;5736.311642602915;5740.397258287903;5583.944160558423;5584.441555844383;5613.025096698756;5623;5626.634268772516;5501.9174712379345;5701.743421545957;5741.8564535886935;5746.224259724883;5751.769929202868;5409.323834520685;5524.088525393265;5538.086215774352;5542.702092255102;5555.2500412445415;5450.91823622638;5454.699991500505;5492.258545096361;5495.708508500419;5501.752421140978;5799.130130480462;5806.332271788629;5816.796412125555;5819.592628516132;5821.94178058214;3430.485427185942;3553.5025096485997;3589.3130228792425;3593.460980852893;3602.558976409457;5415.404305526467;5420.620556561161;5449.400565937664;5488.451154884479;5494;5228.268042473981;5299.205119232146;5305.204219367126;5319.308489896289;5337.172738225581;5068.2904393384215;5296.464025432581;5310.283111780015;5338.679679219259;5342.278792362981;5202.97398513005;5237.397699264633;5239.607029472729;5256.211568264791;5261.947380526299;5099.898002040012;5335.67985920846;5353.705546194997;5360.061989151961;5394.190871369326;5173.094708426086;5175.301334319819;5240.187770895517;5271.630985831109;5272.130098533699
21:37:44 INFO - PROCESS | 2564 | |5;yui.html;617.1729881958178;658.9406953374197;660.3825423229284;668.8762578922899;687.7455341523637;14405.927970360146;14439.711205775879;15176.62058448538;15354.078755274677;15944.760828587581;14646.194459304734;15090.698186036272;15102.320395582197;15150.469733559328;15188.84811151888;644.5971267957526;655.6852710698868;657.4116166031399;661.2131563439492;661.3188415931598;518.3624142304967;529.3726933583696;634.3973225435832;640.69887153038;660.8116686744279;675.8040168351172;692.4529621598931;693.3482526425145;693.8855088910324;694.7498900395854;689.6620655878634;690.2476300832117;690.5131882023162;691.1567887177661;691.194758106805;391.344497965906;398.8344836892686;400.16166131953804;485.92954021667015;502.93210416593655;14230.075045122137;14524;14599.70800583993;14665.26673666323;14668.119912805267;12881.806772898413;12891.67770805728;12902.80645790314;13396.387704397646;13414.463421463228;12596.433160507799;13078.434697602901;13258.270795106266;13258.486501077223;13310.729956122817;4281;4296.291111966521;4311.353297005443;4351.369051487517;4394.450693663292;84906.15093849045;85385.57307213487;86069;86629.56685216534;86780.56609716974;10195.247283233633;10241.897581024094;10310.948445257876;10327.896721032694;10417.739556511147;19154.52113697169;19244.134013969404;19245.765931495047;19281.22875084981;19292.22831086769;7107.502474826816;7141.214466408691;7171.89242161368;7356.852862942818;7392.075990501187;3850.2299540092095;3889.591592882763;3912.491376121086;3948.4866967294643;4004.7196696231567;2679.7454241846995;2731.576605626131;2800.243934137812;2801.257666718321;2802.5515917453517;523.8716514453984;524.0514668450118;537.5216057708642;544.9155380915964;549.5109352676121;460.930860370947;509.33786078098325;542.9049916264638;571.5798887817451;590.6898878089038;8441.324694024463;8666.133386661284;8736.388452808369;8879.689210877717;9001.639934402681;566.5892228134569;576.4984463516683;590.748931704024;596.9164316995624;610.2981571193075;4186.28833098374;4235.407043013981;4245.363195520635;4306.181825453154;4376.431063961728;102970;110954.44522777497;112601.43699281452;114345.4282728581;114426.52911421454;87086;88625;88682.55658721665;90307.54846225727;97848;79257.60371198106;80394;81085.59457202794;82960.17039829645;82967;8384.32925365957;8683.739487815375;8799.956000220214;8831.161039701217;8833.33749968742;49338;49383.506164939325;49761;50301.748491258775;50551.49448505615;68339.31660683529;69357.65321173362;71187;71443.28556714367;71619;11804.881951180378;12411.317377544321;12449;12450.813237801622;12471.563495277622;15191.696166076837;15299;15320;15464.613384665023;15841.128737919526;13611.455541778218;13826.170429773843;13836.654083647989;14326.498572550328;14459.277036148362;13711.314434278047;13738.175709457464;13911.165330080228;13962.58112256634;13972.371243293874;13465.124766890121;13522;13719.931400342934;13737.244451554861;14196.929015354857;10657.733556661145;10887.18346124028;11016.339019658846;11054.115670746482;11360.091192704398;10718.73203169927;11254.662360129209;11287.435628218393;11314.773704525698;11349.205555611028
21:37:44 INFO - PROCESS | 2564 | __end_tp_report
21:37:44 INFO - PROCESS | 2564 | __start_cc_report
21:37:44 INFO - PROCESS | 2564 | _x_x_mozilla_cycle_collect,3070
21:37:44 INFO - PROCESS | 2564 | __end_cc_report
21:37:44 INFO - PROCESS | 2564 | __startTimestamp1476506264865__endTimestamp
21:37:44 INFO - PROCESS | 2564 |
21:37:44 INFO - PROCESS | 2564 | ------- Summary: start -------
21:37:44 INFO - PROCESS | 2564 | Number of tests: 6
21:37:44 INFO - PROCESS | 2564 |
21:37:44 INFO - PROCESS | 2564 | [#0] dojo.html Cycles:165 Average:4667.01 Median:4883.51 stddev:595.70 (12.2%) stddev-sans-first:577.97
21:37:44 INFO - PROCESS | 2564 | Values: 2737.3 2755.6 2759.6 2764.0 2770.9 4688.2 4821.2 4911.6 4915.2 4928.2 4784.8 4823.1 4827.9 4849.4 4935.5 4192.0 4264.4 4288.4 4315.4 4316.3 4080.3 4197.3 4276.3 4340.7 4350.5 4252.9 4262.7 4325.0 4358.7 4361.0 4187.8 4255.2 4327.9 4342.5 4354.1 4079.2 4165.6 4309.0 4312.1 4319.4 4682.5 4759.8 4763.3 4868.9 4880.0 4796.5 4960.2 5005.6 5056.4 5090.9 5087.2 5186.0 5189.6 5196.2 5211.3 4612.2 4665.5 4738.1 4768.3 4784.1 4969.1 5084.9 5121.1 5216.9 5295.0 4894.0 4934.2 4986.0 5028.3 5064.7 4796.5 4994.8 5107.9 5112.7 5146.8 4872.4 5004.2 5116.7 5117.9 5123.8 4924.0 4942.9 5000.0 5035.2 5138.3 3353.2 3395.2 3411.9 3418.7 3447.5 3042.8 3078.7 3154.3 3159.8 3161.1 4506.4 4655.7 4675.7 4785.1 4785.5 5014.8 5015.7 5114.8 5123.7 5128.7 4404.6 4534.7 4604.9 4646.9 4684.7 4110.3 4192.7 4208.9 4210.3 4218.7 5175.6 5297.7 5325.0 5326.8 5328.3 4848.7 4856.4 4912.4 5025.7 5134.2 5023.3 5058.7 5230.5 5280.7 5339.7 4921.5 5002.6 5024.6 5073.7 5133.9 5093.2 5114.4 5170.5 5217.3 5231.7 4894.9 4963.8 4965.5 4988.1 4993.9 4944.9 4976.8 4981.4 4988.6 4989.4 4838.3 4874.6 4877.6 4883.5 4885.9 4845.0 4985.0 4992.3 4996.4 5007.4 4731.6 4830.3 4926.4 4926.5 4937.0
21:37:44 INFO - PROCESS | 2564 |
21:37:44 INFO - PROCESS | 2564 | [#1] ext.html Cycles:180 Average:56957.62 Median:39482.21 stddev:114985.70 (291.2%) stddev-sans-first:115230.20
21:37:44 INFO - PROCESS | 2564 | Values: 525.0 569.4 583.3 588.8 598.7 28759.0 30211.2 40568.8 40699.0 41237.4 41714.0 41984.6 42348.8 42418.0 42429.0 8116.0 8395.9 8460.3 8523.8 8580.8 8351.3 8419.3 8487.5 8494.0 8514.4 8025.4 8051.0 8279.0 8295.5 8460.6 8477.7 8489.9 8500.9 8501.7 8524.2 8458.3 8524.4 8530.3 8531.3 8532.1 38689.8 38821.6 38899.2 39025.6 39118.2 3134.5 3153.5 3210.8 3264.7 3693.7 30486.7 30699.4 31091.8 31644.4 31960.5 3202.2 3260.9 3281.8 3282.4 3309.9 317544.0 322667.0 342783.0 343862.0 345520.0 49285.3 50535.2 50690.0 50721.7 50731.5 53623.5 53625.5 56756.7 56996.0 57058.7 14246.3 14510.4 14540.2 14555.0 14613.9 11114.8 11213.0 11237.0 11276.2 11277.0 17877.0 17935.7 17985.0 18020.5 18210.5 7031.8 7211.6 7228.5 7257.2 7259.6 769.6 897.4 900.4 939.5 981.2 26851.9 27336.2 27386.5 27435.6 28350.4 881.4 904.6 941.2 967.7 969.1 14064.5 14386.0 14483.3 14533.6 14768.1 494369.0 500377.0 505655.0 506079.0 508284.0 47356.3 47357.8 49436.3 49990.3 50497.7 1091.8 1140.1 1144.6 1151.1 1154.8 26438.3 26605.7 27409.0 27749.6 27801.6 43053.6 43860.3 44967.6 44982.0 44988.6 438401.0 443335.8 444035.0 447886.0 449521.0 31657.0 31826.5 37948.1 38680.6 39119.2 40772.4 42657.0 42879.1 43409.0 44092.8 36540.0 36585.6 36884.8 38430.0 38979.6 37299.8 37858.4 38175.4 38561.6 39845.2 34861.3 35155.8 35262.5 35639.5 35788.8 31571.8 32832.3 33568.0 34160.5 34644.7 23661.4 25311.2 25383.0 25490.0 25976.0
21:37:44 INFO - PROCESS | 2564 |
21:37:44 INFO - PROCESS | 2564 | [#2] jquery.html Cycles:180 Average:27826.18 Median:431587.50 stddev:73696.82 (17.1%) stddev-sans-first:73874.58
21:37:44 INFO - PROCESS | 2564 | Values: 299.5 323.1 323.3 330.6 333.7 13223.6 18043.7 18511.5 18722.9 19174.8 17348.0 17703.5 17900.6 18149.2 18199.0 4964.0 5185.2 5590.1 5686.9 5691.1 6160.2 6354.5 6419.5 6429.5 6434.5 6147.5 6298.1 6336.2 6343.1 6360.6 6282.9 6315.5 6327.9 6335.9 6348.9 5247.8 5412.1 5443.2 5446.3 5454.7 22109.3 22450.7 23296.0 23324.0 23397.4 1931.2 1959.8 2031.2 2740.1 2760.9 13387.5 13993.9 14377.3 14612.1 15025.8 1761.2 1803.3 1823.2 1862.4 1875.1 81907.0 83296.0 84045.2 84100.0 84499.6 9015.6 9177.0 9243.6 9347.1 9612.0 11235.7 11615.0 11753.1 12192.4 12258.8 7209.4 7269.8 7298.4 7305.5 7408.4 7157.3 7268.9 7301.5 7302.9 7458.6 3728.6 3747.4 3807.7 3865.4 3892.3 1623.5 1635.8 1663.3 1666.9 1676.5 832.9 840.8 866.3 893.9 911.4 18251.7 18339.8 18509.5 18819.7 19478.1 815.9 822.1 827.0 868.1 931.1 9722.4 9729.9 10067.7 10095.8 10203.8 431174.0 432001.0 444189.0 451499.0 451559.0 99505.3 100919.0 101809.5 102797.0 102817.5 845.7 877.4 880.2 882.6 883.5 14014.7 16279.4 16833.8 17675.7 17892.3 44791.0 45820.5 45958.1 46075.3 46369.0 61668.4 63734.7 64081.4 64368.4 64685.7 7274.7 7359.1 7360.4 7374.9 7478.9 8867.0 12196.3 12696.3 12723.9 13278.2 10595.3 10674.6 10726.9 11007.2 11061.8 10079.0 10210.4 10493.6 10603.8 10861.8 10874.2 10895.2 10927.6 11066.6 11180.9 7057.1 7197.2 7320.9 7352.2 7397.4 9970.0 10003.9 10567.4 10756.2 10793.6
21:37:44 INFO - PROCESS | 2564 |
21:37:44 INFO - PROCESS | 2564 | [#3] mootools.html Cycles:180 Average:4626.44 Median:5623.10 stddev:2579.46 (45.9%) stddev-sans-first:2573.46
21:37:44 INFO - PROCESS | 2564 | Values: 1149.0 1167.0 1264.4 1483.9 1522.4 6877.0 7405.3 7405.4 7407.9 7408.7 6407.9 6571.0 6949.1 7082.3 7250.5 1937.1 1969.8 1971.5 1971.5 1973.3 1848.6 1887.3 1924.1 1924.5 1955.3 1662.7 1678.5 1902.8 1915.6 1958.4 1928.1 1940.6 1940.7 1941.6 1941.6 1846.6 1864.7 1868.5 1885.2 1894.1 6949.4 7016.3 7120.8 7130.0 7130.4 4218.3 4322.2 4326.8 4328.4 4332.7 2760.4 2875.0 2882.8 2918.9 2921.2 906.8 907.0 911.2 924.2 947.9 9113.1 9191.3 9195.4 9202.1 9221.2 3728.5 3836.7 3846.1 3849.0 3871.8 7076.0 7118.9 7465.5 7475.3 7497.1 4158.1 4216.2 4280.7 4282.1 4286.0 3867.3 4044.4 4045.3 4049.7 4074.0 2897.1 2952.9 2953.5 3026.4 3030.6 926.4 939.0 941.1 941.3 947.6 733.7 764.6 774.4 775.2 853.1 4587.7 4879.5 4902.1 4944.8 5029.1 333.3 338.7 340.8 342.7 344.1 2996.5 3007.0 3088.9 3128.7 3185.1 8337.9 8559.5 8603.8 8613.0 8618.5 8579.0 8607.9 8639.2 8664.7 8668.0 6384.5 6746.8 6837.7 6840.3 6844.0 4621.9 4857.4 4862.8 4906.3 5000.5 7976.9 8102.8 8123.5 8134.0 8158.2 8010.6 8196.8 8212.1 8262.8 8274.7 5549.8 5815.7 5850.6 5899.4 5909.3 6652.7 6736.6 7052.0 7070.6 7075.2 5696.4 5930.9 6273.4 6355.7 6377.2 5836.0 6149.9 6271.5 6293.4 6352.7 5763.4 5807.1 5810.3 5837.1 5842.7 4343.7 4379.1 4416.9 4442.0 4519.9 4480.1 4480.6 4497.6 4522.1 4609.2
21:37:44 INFO - PROCESS | 2564 |
21:37:44 INFO - PROCESS | 2564 | [#4] prototype.html Cycles:180 Average:4404.64 Median:5058.20 stddev:1379.21 (27.3%) stddev-sans-first:1347.49
21:37:44 INFO - PROCESS | 2564 | Values: 257.3 266.3 267.7 269.2 269.2 4975.3 5071.8 5124.2 5124.7 5139.5 4943.6 5032.3 5038.4 5046.4 5048.1 3693.9 3724.9 3725.1 3733.5 3733.9 3585.9 3656.2 3679.9 3693.9 3708.7 3587.7 3639.1 3645.0 3645.6 3661.3 3666.7 3696.2 3717.2 3718.9 3720.2 3190.2 3196.0 3212.1 3212.3 3222.8 4922.9 4948.2 4957.5 4961.4 4966.8 4210.1 4323.7 4332.0 4349.3 4384.3 5234.0 5252.2 5253.1 5255.8 5269.2 3555.5 3564.9 3580.2 3581.1 3582.9 5492.7 5509.2 5526.3 5527.2 5550.7 3559.6 3653.9 3659.7 3664.7 3671.7 3699.8 3718.6 3724.2 3726.3 3730.7 4317.3 4332.3 4346.2 4346.3 4351.3 5317.4 5333.9 5340.3 5345.6 5346.4 1269.2 1279.1 1286.5 1287.1 1291.2 798.4 815.4 816.4 827.0 827.3 4886.3 5002.5 5016.7 5038.5 5042.5 5417.5 5476.8 5505.2 5513.9 5518.7 4971.5 4971.8 4999.7 5024.8 5036.1 2765.8 2838.5 2859.6 2897.7 2906.4 5646.7 5721.8 5724.0 5736.3 5740.4 5583.9 5584.4 5613.0 5623.0 5626.6 5501.9 5701.7 5741.9 5746.2 5751.8 5409.3 5524.1 5538.1 5542.7 5555.3 5450.9 5454.7 5492.3 5495.7 5501.8 5799.1 5806.3 5816.8 5819.6 5821.9 3430.5 3553.5 3589.3 3593.5 3602.6 5415.4 5420.6 5449.4 5488.5 5494.0 5228.3 5299.2 5305.2 5319.3 5337.2 5068.3 5296.5 5310.3 5338.7 5342.3 5203.0 5237.4 5239.6 5256.2 5261.9 5099.9 5335.7 5353.7 5360.1 5394.2 5173.1 5175.3 5240.2 5271.6 5272.1
21:37:44 INFO - PROCESS | 2564 |
21:37:44 INFO - PROCESS | 2564 | [#5] yui.html Cycles:180 Average:20176.78 Median:4263.18 stddev:29100.78 (682.6%) stddev-sans-first:29145.36
21:37:44 INFO - PROCESS | 2564 | Values: 617.2 658.9 660.4 668.9 687.7 14405.9 14439.7 15176.6 15354.1 15944.8 14646.2 15090.7 15102.3 15150.5 15188.8 644.6 655.7 657.4 661.2 661.3 518.4 529.4 634.4 640.7 660.8 675.8 692.5 693.3 693.9 694.7 689.7 690.2 690.5 691.2 691.2 391.3 398.8 400.2 485.9 502.9 14230.1 14524.0 14599.7 14665.3 14668.1 12881.8 12891.7 12902.8 13396.4 13414.5 12596.4 13078.4 13258.3 13258.5 13310.7 4281.0 4296.3 4311.4 4351.4 4394.5 84906.2 85385.6 86069.0 86629.6 86780.6 10195.2 10241.9 10310.9 10327.9 10417.7 19154.5 19244.1 19245.8 19281.2 19292.2 7107.5 7141.2 7171.9 7356.9 7392.1 3850.2 3889.6 3912.5 3948.5 4004.7 2679.7 2731.6 2800.2 2801.3 2802.6 523.9 524.1 537.5 544.9 549.5 460.9 509.3 542.9 571.6 590.7 8441.3 8666.1 8736.4 8879.7 9001.6 566.6 576.5 590.7 596.9 610.3 4186.3 4235.4 4245.4 4306.2 4376.4 102970.0 110954.4 112601.4 114345.4 114426.5 87086.0 88625.0 88682.6 90307.5 97848.0 79257.6 80394.0 81085.6 82960.2 82967.0 8384.3 8683.7 8800.0 8831.2 8833.3 49338.0 49383.5 49761.0 50301.7 50551.5 68339.3 69357.7 71187.0 71443.3 71619.0 11804.9 12411.3 12449.0 12450.8 12471.6 15191.7 15299.0 15320.0 15464.6 15841.1 13611.5 13826.2 13836.7 14326.5 14459.3 13711.3 13738.2 13911.2 13962.6 13972.4 13465.1 13522.0 13719.9 13737.2 14196.9 10657.7 10887.2 11016.3 11054.1 11360.1 10718.7 11254.7 11287.4 11314.8 11349.2
21:37:44 INFO - PROCESS | 2564 | -------- Summary: end --------
21:37:44 INFO - PROCESS | 2564 |
21:37:45 INFO - TEST-INFO | 2564: exit 0
21:37:46 INFO - TEST-OK | dromaeo_css | took 1121205ms
21:37:46 INFO - TEST-START | kraken
21:37:46 INFO - operating with platform_type : w7_
21:37:46 INFO - Initialising browser for kraken test...
21:37:46 INFO - TEST-INFO | started process 3104 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmp2_scul\profile http://localhost:49891/getInfo.html)
21:37:50 INFO - PROCESS | 3104 | __metrics Screen width/height:1600/1200
21:37:50 INFO - PROCESS | 3104 | colorDepth:24
21:37:50 INFO - PROCESS | 3104 | Browser inner width/height: 1010/674
21:37:50 INFO - PROCESS | 3104 | __metrics
21:37:51 INFO - PROCESS | 3104 | 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"
21:37:51 INFO - PROCESS | 3104 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
21:37:51 INFO - PROCESS | 3104 | console.error:
21:37:51 INFO - PROCESS | 3104 | Message: Error: Connection closed before committing the transaction.
21:37:51 INFO - PROCESS | 3104 | Stack:
21:37:51 INFO - PROCESS | 3104 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:937:23
21:37:51 INFO - PROCESS | 3104 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7
21:37:51 INFO - PROCESS | 3104 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11
21:37:51 INFO - PROCESS | 3104 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7
21:37:51 INFO - PROCESS | 3104 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7
21:37:51 INFO - PROCESS | 3104 | TaskImpl_run@resource://gre/modules/Task.jsm:327:15
21:37:51 INFO - PROCESS | 3104 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9
21:37:51 INFO - PROCESS | 3104 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15
21:37:51 INFO - PROCESS | 3104 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5
21:37:51 INFO - PROCESS | 3104 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29
21:37:51 INFO - PROCESS | 3104 | Barrier/this.client.addBlocker/promise13.1%
21:38:48 INFO - TinderboxPrint: I/O read bytes / time
96,580,096 / 1,958,690
21:38:48 INFO - TinderboxPrint: I/O write bytes / time
445,931,520 / 3,586,090
21:38:48 INFO - TinderboxPrint: CPU idle
8,268.0 (86.9%)
21:38:48 INFO - TinderboxPrint: CPU user
1,207.2 (12.7%)
21:38:48 INFO - install - Wall time: 4s; CPU: 6.0%; Read bytes: 0; Write bytes: 72584192; Read time: 0; Write time: 621100
21:38:48 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 96475648; Write bytes: 367723520; Read time: 1933340; Write time: 2947400
21:38:49 INFO - Running post-run listener: _upload_blobber_files
21:38:49 INFO - Blob upload gear active.
21:38:49 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir.
21:38:49 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
21:38:49 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']
21:38:49 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
21:38:50 INFO - (blobuploader) - INFO - Open directory for files ...
21:38:50 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log ...
21:38:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
21:38:50 INFO - (blobuploader) - INFO - Uploading, attempt #1.
21:38:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded
21:38:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
21:38:50 INFO - (blobuploader) - INFO - Done attempting.
21:38:50 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log ...
21:38:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
21:38:50 INFO - (blobuploader) - INFO - Uploading, attempt #1.
21:38:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded
21:38:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
21:38:51 INFO - (blobuploader) - INFO - Done attempting.
21:38:51 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ...
21:38:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
21:38:51 INFO - (blobuploader) - INFO - Uploading, attempt #1.
21:38:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
21:38:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
21:38:59 INFO - (blobuploader) - INFO - Done attempting.
21:38:59 INFO - (blobuploader) - INFO - Iteration through files over.
21:38:59 INFO - Return code: 0
21:38:59 INFO - rmtree: C:\slave\test\build\uploaded_files.json
21:38:59 INFO - Using _rmtree_windows ...
21:38:59 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1
21:38:59 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a5c4924e1ba8111f1ef775e471a790ef2dbac0aa7723c392df7cd54105fb2468a52746c07545b2495f306bd451437aa0b03d55f5b71f53ef6e2c8c0ed73d7fe6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d6978c1b84212c2f9d2fc8f14aca80bc0bc8e4b892c6705e9d9e7d334cf671b97d62ab5e7eb25b9df53974fe04f7092b09ebbd91b84c7cfe38e79c4ba10f1201", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9f43e70ebbb2ad120922d5ca17b3901578ef6a8188a49a91060a559fb177addc9f340a631d537c89f0bc3930e1ccd9ddcae384317170415fca2f2e3cbade9fff"}
21:38:59 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files
21:38:59 INFO - Writing to file C:\slave\test\properties\blobber_files
21:38:59 INFO - Contents:
21:38:59 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a5c4924e1ba8111f1ef775e471a790ef2dbac0aa7723c392df7cd54105fb2468a52746c07545b2495f306bd451437aa0b03d55f5b71f53ef6e2c8c0ed73d7fe6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d6978c1b84212c2f9d2fc8f14aca80bc0bc8e4b892c6705e9d9e7d334cf671b97d62ab5e7eb25b9df53974fe04f7092b09ebbd91b84c7cfe38e79c4ba10f1201", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9f43e70ebbb2ad120922d5ca17b3901578ef6a8188a49a91060a559fb177addc9f340a631d537c89f0bc3930e1ccd9ddcae384317170415fca2f2e3cbade9fff"}
21:38:59 INFO - Running post-run listener: copy_logs_to_upload_dir
21:38:59 INFO - Copying logs to upload dir...
21:38:59 INFO - mkdir: C:\slave\test\build\upload\logs
21:38:59 INFO - Copying logs to upload dir...
21:38:59 INFO - Using _rmtree_windows ...
21:38:59 INFO - Using _rmtree_windows ...
21:38:59 INFO - Using _rmtree_windows ...
21:38:59 INFO - Using _rmtree_windows ...
21:38:59 INFO - Using _rmtree_windows ...
21:38:59 INFO - Using _rmtree_windows ...
21:38:59 INFO - Using _rmtree_windows ...
program finished with exit code 0
elapsedTime=1480.419000
========= master_lag: 0.02 =========
========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 40 secs) (at 2016-10-14 21:38:59.147453) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-14 21:38:59.153351) =========
'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-055
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-055
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-055
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/a5c4924e1ba8111f1ef775e471a790ef2dbac0aa7723c392df7cd54105fb2468a52746c07545b2495f306bd451437aa0b03d55f5b71f53ef6e2c8c0ed73d7fe6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d6978c1b84212c2f9d2fc8f14aca80bc0bc8e4b892c6705e9d9e7d334cf671b97d62ab5e7eb25b9df53974fe04f7092b09ebbd91b84c7cfe38e79c4ba10f1201", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9f43e70ebbb2ad120922d5ca17b3901578ef6a8188a49a91060a559fb177addc9f340a631d537c89f0bc3930e1ccd9ddcae384317170415fca2f2e3cbade9fff"}
build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476495004/firefox-52.0a1.en-US.win32.zip
program finished with exit code 0
elapsedTime=0.101000
build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476495004/firefox-52.0a1.en-US.win32.zip'
blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a5c4924e1ba8111f1ef775e471a790ef2dbac0aa7723c392df7cd54105fb2468a52746c07545b2495f306bd451437aa0b03d55f5b71f53ef6e2c8c0ed73d7fe6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d6978c1b84212c2f9d2fc8f14aca80bc0bc8e4b892c6705e9d9e7d334cf671b97d62ab5e7eb25b9df53974fe04f7092b09ebbd91b84c7cfe38e79c4ba10f1201", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9f43e70ebbb2ad120922d5ca17b3901578ef6a8188a49a91060a559fb177addc9f340a631d537c89f0bc3930e1ccd9ddcae384317170415fca2f2e3cbade9fff"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-14 21:38:59.275706) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 21:38:59.276053) =========
'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-055
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-055
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-055
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
program finished with exit code 0
elapsedTime=0.101000
========= master_lag: 0.01 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 21:38:59.390846) =========
========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-10-14 21:38:59.391175) =========
========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-10-14 21:38:59.879738) =========
========= Total master_lag: 3.79 =========