builder: autoland_win7_ix_test-dromaeojs
slave: t-w732-ix-123
starttime: 1474572719.27
results: success (0)
buildid: 20160922113659
builduid: 35a5d152992a4b7a9b055267f3aef4df
revision: 70c6f01a830b3a39eb8ac1abf828312de48579aa
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-22 12:31:59.271211) =========
master: http://buildbot-master127.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-22 12:31:59.271675) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-22 12:31:59.271969) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-22 12:31:59.474755) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-22 12:31:59.475136) =========
'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-123
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-123
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-123
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
--12:32:01-- 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.
HTTP request sent, awaiting response... 200 Script output follows
Length: 12,141 (12K) [text/x-python]
0K .......... . 100% 8.25 MB/s
12:32:01 (8.25 MB/s) - `archiver_client.py' saved [12141/12141]
program finished with exit code 0
elapsedTime=1.684000
========= master_lag: 0.04 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-22 12:32:01.196512) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 13 secs) (at 2016-09-22 12:32:01.196968) =========
'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-123
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-123
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-123
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=13.301000
========= master_lag: 0.03 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 13 secs) (at 2016-09-22 12:32:14.527312) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-22 12:32:14.527695) =========
'bash' '-c' u'python archiver_client.py mozharness --repo integration/autoland --rev 70c6f01a830b3a39eb8ac1abf828312de48579aa --destination scripts --debug'
in dir C:\slave\test\. (timeout 1200 secs)
watching logfiles {}
argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 70c6f01a830b3a39eb8ac1abf828312de48579aa --destination scripts --debug']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-123
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-123
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-123
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-09-22 12:32:15,483 truncating revision to first 12 chars
2016-09-22 12:32:15,483 Setting DEBUG logging.
2016-09-22 12:32:15,483 attempt 1/10
2016-09-22 12:32:15,483 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/70c6f01a830b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2016-09-22 12:32:16,062 unpacking tar archive at: autoland-70c6f01a830b/testing/mozharness/
2016-09-22 12:32:16,207 skipping symlink on windows: configs/single_locale/linux32.py
program finished with exit code 0
elapsedTime=1.326000
========= master_lag: 0.06 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-22 12:32:15.909414) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-22 12:32:15.909812) =========
script_repo_revision: 70c6f01a830b3a39eb8ac1abf828312de48579aa
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-22 12:32:15.910251) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-22 12:32:15.910591) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-22 12:32:15.921369) =========
========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 1 secs) (at 2016-09-22 12:32:15.921691) =========
'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Autoland-Non-PGO' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Autoland-Non-PGO'
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', 'Autoland-Non-PGO', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland-Non-PGO']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-123
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-123
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-123
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
12:32:17 INFO - MultiFileLogger online at 20160922 12:32:17 in C:\slave\test
12:32:17 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO
12:32:17 INFO - Dumping config to C:\slave\test\logs\localconfig.json.
12:32:17 INFO - {'append_to_log': False,
12:32:17 INFO - 'base_work_dir': 'C:\\slave\\test',
12:32:17 INFO - 'blob_upload_branch': 'Autoland-Non-PGO',
12:32:17 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt',
12:32:17 INFO - 'branch': 'Autoland-Non-PGO',
12:32:17 INFO - 'buildbot_json_path': 'buildprops.json',
12:32:17 INFO - 'config_files': ('talos/windows_config.py',),
12:32:17 INFO - 'default_actions': ('clobber',
12:32:17 INFO - 'read-buildbot-config',
12:32:17 INFO - 'download-and-extract',
12:32:17 INFO - 'populate-webroot',
12:32:17 INFO - 'create-virtualenv',
12:32:17 INFO - 'install',
12:32:17 INFO - 'run-tests'),
12:32:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
12:32:17 INFO - 'download_minidump_stackwalk': True,
12:32:17 INFO - 'download_symbols': 'ondemand',
12:32:17 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python',
12:32:17 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'),
12:32:17 INFO - 'hg': 'c:/mozilla-build/hg/hg',
12:32:17 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python',
12:32:17 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'),
12:32:17 INFO - 'python': 'c:/mozilla-build/python27/python.exe',
12:32:17 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe',
12:32:17 INFO - 'C:/mozilla-build/tooltool.py'),
12:32:17 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe',
12:32:17 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')},
12:32:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
12:32:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
12:32:17 INFO - 'installer_path': 'installer.exe',
12:32:17 INFO - 'log_level': 'info',
12:32:17 INFO - 'log_name': 'talos',
12:32:17 INFO - 'log_to_console': True,
12:32:17 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe',
12:32:17 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe',
12:32:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest',
12:32:17 INFO - 'opt_config_files': (),
12:32:17 INFO - 'pip_index': False,
12:32:17 INFO - 'sps_profile': False,
12:32:17 INFO - 'sps_profile_interval': 0,
12:32:17 INFO - 'suite': 'dromaeojs',
12:32:17 INFO - 'system_bits': '32',
12:32:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
12:32:17 INFO - 'title': 'T-W732-IX-123',
12:32:17 INFO - 'use_talos_json': True,
12:32:17 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'),
12:32:17 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv',
12:32:17 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll',
12:32:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
12:32:17 INFO - 'work_dir': 'build'}
12:32:17 INFO - [mozharness: 2016-09-22 19:32:17.095000Z] Running clobber step.
12:32:17 INFO - Running pre-action listener: _resource_record_pre_action
12:32:17 INFO - Running main action method: clobber
12:32:17 INFO - rmtree: C:\slave\test\build
12:32:17 INFO - Using _rmtree_windows ...
12:32:17 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1
12:34:30 INFO - Running post-action listener: _resource_record_post_action
12:34:30 INFO - [mozharness: 2016-09-22 19:34:30.583000Z] Finished clobber step (success)
12:34:30 INFO - [mozharness: 2016-09-22 19:34:30.583000Z] Running read-buildbot-config step.
12:34:30 INFO - Running pre-action listener: _resource_record_pre_action
12:34:30 INFO - Running main action method: read_buildbot_config
12:34:30 INFO - Using buildbot properties:
12:34:30 INFO - {
12:34:30 INFO - "project": "",
12:34:30 INFO - "product": "firefox",
12:34:30 INFO - "script_repo_revision": "production",
12:34:30 INFO - "builddir": "autoland_win7_ix_test-dromaeojs",
12:34:30 INFO - "repository": "",
12:34:30 INFO - "buildername": "Windows 7 32-bit autoland talos dromaeojs",
12:34:30 INFO - "buildid": "20160922113659",
12:34:30 INFO - "pgo_build": "False",
12:34:30 INFO - "basedir": "C:\\slave\\test",
12:34:30 INFO - "buildnumber": 241,
12:34:30 INFO - "slavename": "t-w732-ix-123",
12:34:30 INFO - "master": "http://buildbot-master127.bb.releng.scl3.mozilla.com:8201/",
12:34:30 INFO - "platform": "win7_ix",
12:34:30 INFO - "scheduler": "tests-autoland-win32-talos",
12:34:30 INFO - "branch": "autoland",
12:34:30 INFO - "repo_path": "integration/autoland",
12:34:30 INFO - "revision": "70c6f01a830b3a39eb8ac1abf828312de48579aa",
12:34:30 INFO - "stage_platform": "win32",
12:34:30 INFO - "builduid": "35a5d152992a4b7a9b055267f3aef4df",
12:34:30 INFO - "slavebuilddir": "test"
12:34:30 INFO - }
12:34:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/La_s1kTOTIm9u6v6rE6Udw/artifacts/public/build/firefox-52.0a1.en-US.win32.zip.
12:34:30 INFO - Running post-action listener: _resource_record_post_action
12:34:30 INFO - [mozharness: 2016-09-22 19:34:30.587000Z] Finished read-buildbot-config step (success)
12:34:30 INFO - [mozharness: 2016-09-22 19:34:30.587000Z] Running download-and-extract step.
12:34:30 INFO - Running pre-action listener: _resource_record_pre_action
12:34:30 INFO - Running main action method: download_and_extract
12:34:30 INFO - mkdir: C:\slave\test\build\tests
12:34: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')]}
12:34:30 INFO - trying https://queue.taskcluster.net/v1/task/La_s1kTOTIm9u6v6rE6Udw/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json
12:34:30 INFO - Downloading https://queue.taskcluster.net/v1/task/La_s1kTOTIm9u6v6rE6Udw/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json to C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json
12:34:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/La_s1kTOTIm9u6v6rE6Udw/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json', 'file_name': 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.test_packages.json'}, attempt #1
12:34:31 INFO - Downloaded 1274 bytes.
12:34:31 INFO - Reading from file C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json
12:34:31 INFO - Using the following test package requirements:
12:34:31 INFO - {u'common': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
12:34:31 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
12:34:31 INFO - u'firefox-52.0a1.en-US.win32.cppunittest.tests.zip'],
12:34:31 INFO - u'gtest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
12:34:31 INFO - u'firefox-52.0a1.en-US.win32.gtest.tests.zip'],
12:34:31 INFO - u'jittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
12:34:31 INFO - u'jsshell-win32.zip'],
12:34:31 INFO - u'mochitest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
12:34:31 INFO - u'firefox-52.0a1.en-US.win32.mochitest.tests.zip'],
12:34:31 INFO - u'mozbase': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
12:34:31 INFO - u'reftest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
12:34:31 INFO - u'firefox-52.0a1.en-US.win32.reftest.tests.zip'],
12:34:31 INFO - u'talos': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
12:34:31 INFO - u'firefox-52.0a1.en-US.win32.talos.tests.zip'],
12:34:31 INFO - u'web-platform': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
12:34:31 INFO - u'firefox-52.0a1.en-US.win32.web-platform.tests.zip'],
12:34:31 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
12:34:31 INFO - u'firefox-52.0a1.en-US.win32.xpcshell.tests.zip']}
12:34:31 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip'] for test suite category: common
12:34:31 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/La_s1kTOTIm9u6v6rE6Udw/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip
12:34:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/La_s1kTOTIm9u6v6rE6Udw/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1
12:34:31 INFO - Fetch https://queue.taskcluster.net/v1/task/La_s1kTOTIm9u6v6rE6Udw/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory
12:34:32 INFO - Expected file size: 17286449
12:34:55 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip', u'firefox-52.0a1.en-US.win32.talos.tests.zip'] for test suite category: talos
12:34:55 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/La_s1kTOTIm9u6v6rE6Udw/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip
12:34:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/La_s1kTOTIm9u6v6rE6Udw/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1
12:34:55 INFO - Fetch https://queue.taskcluster.net/v1/task/La_s1kTOTIm9u6v6rE6Udw/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory
12:34:56 INFO - Expected file size: 17286449
12:35:06 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/La_s1kTOTIm9u6v6rE6Udw/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip
12:35:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/La_s1kTOTIm9u6v6rE6Udw/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip'}, attempt #1
12:35:06 INFO - Fetch https://queue.taskcluster.net/v1/task/La_s1kTOTIm9u6v6rE6Udw/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip into memory
12:35:06 INFO - Expected file size: 14414059
12:35:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:35:15 INFO - trying https://queue.taskcluster.net/v1/task/La_s1kTOTIm9u6v6rE6Udw/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
12:35:15 INFO - Downloading https://queue.taskcluster.net/v1/task/La_s1kTOTIm9u6v6rE6Udw/artifacts/public/build/firefox-52.0a1.en-US.win32.zip to C:\slave\test\build\firefox-52.0a1.en-US.win32.zip
12:35:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/La_s1kTOTIm9u6v6rE6Udw/artifacts/public/build/firefox-52.0a1.en-US.win32.zip', 'file_name': 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.zip'}, attempt #1
12:35:18 INFO - Downloaded 53476127 bytes.
12:35:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/La_s1kTOTIm9u6v6rE6Udw/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
12:35:18 INFO - mkdir: C:\slave\test\properties
12:35:18 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url
12:35:18 INFO - Writing to file C:\slave\test\properties\build_url
12:35:18 INFO - Contents:
12:35:18 INFO - build_url:https://queue.taskcluster.net/v1/task/La_s1kTOTIm9u6v6rE6Udw/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
12:35:19 INFO - Running post-action listener: _resource_record_post_action
12:35:19 INFO - Running post-action listener: set_extra_try_arguments
12:35:19 INFO - [mozharness: 2016-09-22 19:35:19.347000Z] Finished download-and-extract step (success)
12:35:19 INFO - [mozharness: 2016-09-22 19:35:19.347000Z] Running populate-webroot step.
12:35:19 INFO - Running pre-action listener: _resource_record_pre_action
12:35:19 INFO - Running main action method: populate_webroot
12:35:19 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']},
12:35:19 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']},
12:35:19 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome',
12:35:19 INFO - u'--tppagecycles',
12:35:19 INFO - u'7'],
12:35:19 INFO - u'tests': [u'tsvgm']}},
12:35:19 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'],
12:35:19 INFO - u'tests': [u'tresize', u'tcanvasmark']},
12:35:19 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']},
12:35:19 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'],
12:35:19 INFO - u'tests': [u'dromaeo_css', u'kraken']},
12:35:19 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']},
12:35:19 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
12:35:19 INFO - u'talos_options': [u'--disable-e10s'],
12:35:19 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
12:35:19 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
12:35:19 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
12:35:19 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
12:35:19 INFO - u'talos_options': [u'--disable-e10s'],
12:35:19 INFO - u'tests': [u'damp', u'tps']},
12:35:19 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
12:35:19 INFO - u'tests': [u'damp', u'tps']},
12:35:19 INFO - u'g3': {u'talos_options': [u'--disable-e10s'],
12:35:19 INFO - u'tests': [u'dromaeo_dom']},
12:35:19 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']},
12:35:19 INFO - u'g4': {u'talos_options': [u'--disable-e10s'],
12:35:19 INFO - u'tests': [u'basic_compositor_video']},
12:35:19 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']},
12:35:19 INFO - u'other': {u'talos_options': [u'--disable-e10s'],
12:35:19 INFO - u'tests': [u'a11yr',
12:35:19 INFO - u'ts_paint',
12:35:19 INFO - u'tpaint',
12:35:19 INFO - u'sessionrestore',
12:35:19 INFO - u'sessionrestore_no_auto_restore',
12:35:19 INFO - u'tabpaint']},
12:35:19 INFO - u'other-e10s': {u'tests': [u'a11yr',
12:35:19 INFO - u'ts_paint',
12:35:19 INFO - u'tpaint',
12:35:19 INFO - u'sessionrestore',
12:35:19 INFO - u'sessionrestore_no_auto_restore',
12:35:19 INFO - u'tabpaint']},
12:35:19 INFO - u'other-e10s_l64': {u'tests': [u'a11yr',
12:35:19 INFO - u'ts_paint',
12:35:19 INFO - u'tpaint',
12:35:19 INFO - u'sessionrestore',
12:35:19 INFO - u'sessionrestore_no_auto_restore',
12:35:19 INFO - u'tabpaint']},
12:35:19 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr',
12:35:19 INFO - u'ts_paint',
12:35:19 INFO - u'tpaint',
12:35:19 INFO - u'sessionrestore',
12:35:19 INFO - u'sessionrestore_no_auto_restore',
12:35:19 INFO - u'tabpaint']},
12:35:19 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'],
12:35:19 INFO - u'tests': [u'a11yr',
12:35:19 INFO - u'ts_paint',
12:35:19 INFO - u'tpaint',
12:35:19 INFO - u'sessionrestore',
12:35:19 INFO - u'sessionrestore_no_auto_restore',
12:35:19 INFO - u'tabpaint']},
12:35:19 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'],
12:35:19 INFO - u'tests': [u'a11yr',
12:35:19 INFO - u'ts_paint',
12:35:19 INFO - u'tpaint',
12:35:19 INFO - u'sessionrestore',
12:35:19 INFO - u'sessionrestore_no_auto_restore',
12:35:19 INFO - u'tabpaint']},
12:35:19 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'],
12:35:19 INFO - u'tests': [u'tsvgx',
12:35:19 INFO - u'tsvgr_opacity',
12:35:19 INFO - u'tart',
12:35:19 INFO - u'tscrollx',
12:35:19 INFO - u'cart']},
12:35:19 INFO - u'svgr-e10s': {u'tests': [u'tsvgx',
12:35:19 INFO - u'tsvgr_opacity',
12:35:19 INFO - u'tart',
12:35:19 INFO - u'tscrollx',
12:35:19 INFO - u'cart']},
12:35:19 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
12:35:19 INFO - u'talos_options': [u'--disable-e10s'],
12:35:19 INFO - u'tests': [u'tp5o']},
12:35:19 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
12:35:19 INFO - u'tests': [u'tp5o']},
12:35:19 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
12:35:19 INFO - u'talos_options': [u'--disable-e10s',
12:35:19 INFO - u'--xperf_path',
12:35:19 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
12:35:19 INFO - u'tests': [u'tp5n']},
12:35:19 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
12:35:19 INFO - u'talos_options': [u'--xperf_path',
12:35:19 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
12:35:19 INFO - u'tests': [u'tp5n']}},
12:35:19 INFO - u'talos.zip': {u'path': u'',
12:35:19 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}}
12:35:19 INFO - Running post-action listener: _resource_record_post_action
12:35:19 INFO - [mozharness: 2016-09-22 19:35:19.450000Z] Finished populate-webroot step (success)
12:35:19 INFO - [mozharness: 2016-09-22 19:35:19.450000Z] Running create-virtualenv step.
12:35:19 INFO - Running pre-action listener: _resource_record_pre_action
12:35:19 INFO - Running main action method: create_virtualenv
12:35:19 INFO - Creating virtualenv C:\slave\test\build/venv
12:35:19 INFO - mkdir: C:\slave\test\build\venv\Scripts
12:35:19 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll
12:35:19 WARNING - Can't copy c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll: [Errno 2] No such file or directory: 'c:/mozilla-build/python27/python27.dll'!
12:35:19 INFO - Running command: ['c:/mozilla-build/python27/python.exe', 'c:/mozilla-build/buildbotve/virtualenv.py', '--no-site-packages', '--distribute', 'C:\\slave\\test\\build/venv'] in C:\slave\test\build
12:35:19 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv
12:35:26 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe
12:35:30 INFO - Installing distribute......................................................................................................................................................................................done.
12:35:31 INFO - Return code: 0
12:35:31 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv
12:35:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:35:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:35:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:35:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:35: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018CA640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01945E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017E39B0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0191EE90>, '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 0x0131A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EF5520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-123', 'USERDOMAIN': 'T-W732-IX-123', '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-123', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
12:35: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', 'pip>=1.5'] in C:\slave\test\build
12:35: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 pip>=1.5
12:35:31 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
12:35:31 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
12:35:31 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
12:35:31 INFO - 'COMPUTERNAME': 'T-W732-IX-123',
12:35:31 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
12:35:31 INFO - 'CYGWINBASE': 'C:\\cygwin',
12:35:31 INFO - 'DCLOCATION': 'SCL3',
12:35:31 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
12:35:31 INFO - 'FP_NO_HOST_CHECK': 'NO',
12:35:31 INFO - 'HOMEDRIVE': 'C:',
12:35:31 INFO - 'HOMEPATH': '\\Users\\cltbld',
12:35:31 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
12:35:31 INFO - 'KTS_VERSION': '1.19c',
12:35:31 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
12:35:31 INFO - 'LOGONSERVER': '\\\\T-W732-IX-123',
12:35:31 INFO - 'MONDIR': 'C:\\Monitor_config\\',
12:35:31 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
12:35:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:35:31 INFO - 'MOZ_NO_REMOTE': '1',
12:35:31 INFO - 'NO_EM_RESTART': '1',
12:35:31 INFO - 'NUMBER_OF_PROCESSORS': '8',
12:35:31 INFO - 'OS': 'Windows_NT',
12:35:31 INFO - 'OURDRIVE': 'C:',
12:35:31 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
12:35:31 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
12:35:31 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
12:35:31 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
12:35:31 INFO - 'PROCESSOR_LEVEL': '6',
12:35:31 INFO - 'PROCESSOR_REVISION': '1e05',
12:35:31 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
12:35:31 INFO - 'PROGRAMFILES': 'C:\\Program Files',
12:35:31 INFO - 'PROMPT': '$P$G',
12:35:31 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
12:35:31 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
12:35:31 INFO - 'PUBLIC': 'C:\\Users\\Public',
12:35:31 INFO - 'PWD': 'C:\\slave\\test',
12:35:31 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
12:35:31 INFO - 'SLAVEDIR': 'C:\\slave\\',
12:35:31 INFO - 'SYSTEMDRIVE': 'C:',
12:35:31 INFO - 'SYSTEMROOT': 'C:\\windows',
12:35:31 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:35:31 INFO - 'TEST1': 'testie',
12:35:31 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:35:31 INFO - 'USERDOMAIN': 'T-W732-IX-123',
12:35:31 INFO - 'USERNAME': 'cltbld',
12:35:31 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
12:35:31 INFO - 'WINDIR': 'C:\\windows',
12:35:31 INFO - 'WINDOWS_TRACING_FLAGS': '3',
12:35:31 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
12:35:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
12:35:33 INFO - Ignoring indexes: http://pypi.python.org/simple/
12:35:33 INFO - Downloading/unpacking pip>=1.5
12:35:37 INFO - Running setup.py egg_info for package pip
12:35:37 INFO - warning: no files found matching 'pip\cacert.pem'
12:35:37 INFO - warning: no files found matching '*.html' under directory 'docs'
12:35:37 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
12:35:37 INFO - no previously-included directories found matching 'docs\_build\_sources'
12:35:37 INFO - Installing collected packages: pip
12:35:37 INFO - Found existing installation: pip 0.8.2
12:35:37 INFO - Uninstalling pip:
12:35:37 INFO - Successfully uninstalled pip
12:35:37 INFO - Running setup.py install for pip
12:35:37 INFO - warning: no files found matching 'pip\cacert.pem'
12:35:37 INFO - warning: no files found matching '*.html' under directory 'docs'
12:35:37 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
12:35:37 INFO - no previously-included directories found matching 'docs\_build\_sources'
12:35:37 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts
12:35:37 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts
12:35:37 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts
12:35:37 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts
12:35:37 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts
12:35:37 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts
12:35:37 INFO - Successfully installed pip
12:35:37 INFO - Cleaning up...
12:35:37 INFO - Return code: 0
12:35:37 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv
12:35:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:35:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:35:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:35:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:35:37 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 0x018CA640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01945E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017E39B0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0191EE90>, '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 0x0131A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EF5520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-123', 'USERDOMAIN': 'T-W732-IX-123', '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-123', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
12:35:37 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in C:\slave\test\build
12:35:37 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1
12:35:37 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
12:35:37 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
12:35:37 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
12:35:37 INFO - 'COMPUTERNAME': 'T-W732-IX-123',
12:35:37 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
12:35:37 INFO - 'CYGWINBASE': 'C:\\cygwin',
12:35:37 INFO - 'DCLOCATION': 'SCL3',
12:35:37 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
12:35:37 INFO - 'FP_NO_HOST_CHECK': 'NO',
12:35:37 INFO - 'HOMEDRIVE': 'C:',
12:35:37 INFO - 'HOMEPATH': '\\Users\\cltbld',
12:35:37 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
12:35:37 INFO - 'KTS_VERSION': '1.19c',
12:35:37 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
12:35:37 INFO - 'LOGONSERVER': '\\\\T-W732-IX-123',
12:35:37 INFO - 'MONDIR': 'C:\\Monitor_config\\',
12:35:37 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
12:35:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:35:37 INFO - 'MOZ_NO_REMOTE': '1',
12:35:37 INFO - 'NO_EM_RESTART': '1',
12:35:37 INFO - 'NUMBER_OF_PROCESSORS': '8',
12:35:37 INFO - 'OS': 'Windows_NT',
12:35:37 INFO - 'OURDRIVE': 'C:',
12:35:37 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
12:35:37 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
12:35:37 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
12:35:37 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
12:35:37 INFO - 'PROCESSOR_LEVEL': '6',
12:35:37 INFO - 'PROCESSOR_REVISION': '1e05',
12:35:37 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
12:35:37 INFO - 'PROGRAMFILES': 'C:\\Program Files',
12:35:37 INFO - 'PROMPT': '$P$G',
12:35:37 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
12:35:37 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
12:35:37 INFO - 'PUBLIC': 'C:\\Users\\Public',
12:35:37 INFO - 'PWD': 'C:\\slave\\test',
12:35:37 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
12:35:37 INFO - 'SLAVEDIR': 'C:\\slave\\',
12:35:37 INFO - 'SYSTEMDRIVE': 'C:',
12:35:37 INFO - 'SYSTEMROOT': 'C:\\windows',
12:35:37 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:35:37 INFO - 'TEST1': 'testie',
12:35:37 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:35:37 INFO - 'USERDOMAIN': 'T-W732-IX-123',
12:35:37 INFO - 'USERNAME': 'cltbld',
12:35:37 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
12:35:37 INFO - 'WINDIR': 'C:\\windows',
12:35:37 INFO - 'WINDOWS_TRACING_FLAGS': '3',
12:35:37 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
12:35:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
12:35:38 INFO - Ignoring indexes: https://pypi.python.org/simple/
12:35:38 INFO - Downloading/unpacking psutil>=3.1.1
12:35:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
12:35:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
12:35:39 INFO - Installing collected packages: psutil
12:35:40 INFO - Successfully installed psutil
12:35:40 INFO - Cleaning up...
12:35:40 INFO - Return code: 0
12:35:40 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv
12:35:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:35:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:35:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:35:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:35:40 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018CA640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01945E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017E39B0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0191EE90>, '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 0x0131A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EF5520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-123', 'USERDOMAIN': 'T-W732-IX-123', '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-123', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
12:35:40 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in C:\slave\test\build
12:35:40 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3
12:35:40 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
12:35:40 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
12:35:40 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
12:35:40 INFO - 'COMPUTERNAME': 'T-W732-IX-123',
12:35:40 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
12:35:40 INFO - 'CYGWINBASE': 'C:\\cygwin',
12:35:40 INFO - 'DCLOCATION': 'SCL3',
12:35:40 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
12:35:40 INFO - 'FP_NO_HOST_CHECK': 'NO',
12:35:40 INFO - 'HOMEDRIVE': 'C:',
12:35:40 INFO - 'HOMEPATH': '\\Users\\cltbld',
12:35:40 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
12:35:40 INFO - 'KTS_VERSION': '1.19c',
12:35:40 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
12:35:40 INFO - 'LOGONSERVER': '\\\\T-W732-IX-123',
12:35:40 INFO - 'MONDIR': 'C:\\Monitor_config\\',
12:35:40 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
12:35:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:35:40 INFO - 'MOZ_NO_REMOTE': '1',
12:35:40 INFO - 'NO_EM_RESTART': '1',
12:35:40 INFO - 'NUMBER_OF_PROCESSORS': '8',
12:35:40 INFO - 'OS': 'Windows_NT',
12:35:40 INFO - 'OURDRIVE': 'C:',
12:35:40 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
12:35:40 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
12:35:40 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
12:35:40 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
12:35:40 INFO - 'PROCESSOR_LEVEL': '6',
12:35:40 INFO - 'PROCESSOR_REVISION': '1e05',
12:35:40 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
12:35:40 INFO - 'PROGRAMFILES': 'C:\\Program Files',
12:35:40 INFO - 'PROMPT': '$P$G',
12:35:40 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
12:35:40 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
12:35:40 INFO - 'PUBLIC': 'C:\\Users\\Public',
12:35:40 INFO - 'PWD': 'C:\\slave\\test',
12:35:40 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
12:35:40 INFO - 'SLAVEDIR': 'C:\\slave\\',
12:35:40 INFO - 'SYSTEMDRIVE': 'C:',
12:35:40 INFO - 'SYSTEMROOT': 'C:\\windows',
12:35:40 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:35:40 INFO - 'TEST1': 'testie',
12:35:40 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:35:40 INFO - 'USERDOMAIN': 'T-W732-IX-123',
12:35:40 INFO - 'USERNAME': 'cltbld',
12:35:40 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
12:35:40 INFO - 'WINDIR': 'C:\\windows',
12:35:40 INFO - 'WINDOWS_TRACING_FLAGS': '3',
12:35:40 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
12:35:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
12:35:40 INFO - Ignoring indexes: https://pypi.python.org/simple/
12:35:40 INFO - Downloading/unpacking mozsystemmonitor==0.3
12:35:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
12:35:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
12:35:41 INFO - Downloading mozsystemmonitor-0.3.tar.gz
12:35:41 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor
12:35:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in c:\slave\test\build\venv\lib\site-packages (from mozsystemmonitor==0.3)
12:35:41 INFO - Installing collected packages: mozsystemmonitor
12:35:41 INFO - Running setup.py install for mozsystemmonitor
12:35:42 INFO - Successfully installed mozsystemmonitor
12:35:42 INFO - Cleaning up...
12:35:42 INFO - Return code: 0
12:35:42 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv
12:35:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:35:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:35:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:35:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:35:42 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 0x018CA640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01945E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017E39B0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0191EE90>, '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 0x0131A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EF5520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-123', 'USERDOMAIN': 'T-W732-IX-123', '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-123', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
12:35:42 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in C:\slave\test\build
12:35:42 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1
12:35:42 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
12:35:42 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
12:35:42 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
12:35:42 INFO - 'COMPUTERNAME': 'T-W732-IX-123',
12:35:42 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
12:35:42 INFO - 'CYGWINBASE': 'C:\\cygwin',
12:35:42 INFO - 'DCLOCATION': 'SCL3',
12:35:42 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
12:35:42 INFO - 'FP_NO_HOST_CHECK': 'NO',
12:35:42 INFO - 'HOMEDRIVE': 'C:',
12:35:42 INFO - 'HOMEPATH': '\\Users\\cltbld',
12:35:42 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
12:35:42 INFO - 'KTS_VERSION': '1.19c',
12:35:42 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
12:35:42 INFO - 'LOGONSERVER': '\\\\T-W732-IX-123',
12:35:42 INFO - 'MONDIR': 'C:\\Monitor_config\\',
12:35:42 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
12:35:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:35:42 INFO - 'MOZ_NO_REMOTE': '1',
12:35:42 INFO - 'NO_EM_RESTART': '1',
12:35:42 INFO - 'NUMBER_OF_PROCESSORS': '8',
12:35:42 INFO - 'OS': 'Windows_NT',
12:35:42 INFO - 'OURDRIVE': 'C:',
12:35:42 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
12:35:42 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
12:35:42 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
12:35:42 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
12:35:42 INFO - 'PROCESSOR_LEVEL': '6',
12:35:42 INFO - 'PROCESSOR_REVISION': '1e05',
12:35:42 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
12:35:42 INFO - 'PROGRAMFILES': 'C:\\Program Files',
12:35:42 INFO - 'PROMPT': '$P$G',
12:35:42 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
12:35:42 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
12:35:42 INFO - 'PUBLIC': 'C:\\Users\\Public',
12:35:42 INFO - 'PWD': 'C:\\slave\\test',
12:35:42 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
12:35:42 INFO - 'SLAVEDIR': 'C:\\slave\\',
12:35:42 INFO - 'SYSTEMDRIVE': 'C:',
12:35:42 INFO - 'SYSTEMROOT': 'C:\\windows',
12:35:42 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:35:42 INFO - 'TEST1': 'testie',
12:35:42 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:35:42 INFO - 'USERDOMAIN': 'T-W732-IX-123',
12:35:42 INFO - 'USERNAME': 'cltbld',
12:35:42 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
12:35:42 INFO - 'WINDIR': 'C:\\windows',
12:35:42 INFO - 'WINDOWS_TRACING_FLAGS': '3',
12:35:42 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
12:35:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
12:35:42 INFO - Ignoring indexes: https://pypi.python.org/simple/
12:35:42 INFO - Downloading/unpacking jsonschema==2.5.1
12:35:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
12:35:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
12:35:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl
12:35:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1)
12:35:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
12:35:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
12:35:43 INFO - Downloading functools32-3.2.3-2.tar.gz
12:35:43 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32
12:35:43 INFO - warning: no files found matching '*.txt'
12:35:43 INFO - no previously-included directories found matching 'build'
12:35:43 INFO - no previously-included directories found matching 'dist'
12:35:43 INFO - no previously-included directories found matching '.git*'
12:35:43 INFO - Installing collected packages: jsonschema, functools32
12:35:44 INFO - Running setup.py install for functools32
12:35:44 INFO - warning: no files found matching '*.txt'
12:35:44 INFO - no previously-included directories found matching 'build'
12:35:44 INFO - no previously-included directories found matching 'dist'
12:35:44 INFO - no previously-included directories found matching '.git*'
12:35:44 INFO - Successfully installed jsonschema functools32
12:35:44 INFO - Cleaning up...
12:35:44 INFO - Return code: 0
12:35:44 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv
12:35:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:35:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:35:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:35:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:35:44 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 0x018CA640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01945E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017E39B0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0191EE90>, '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 0x0131A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EF5520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-123', 'USERDOMAIN': 'T-W732-IX-123', '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-123', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
12:35:44 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in C:\slave\test\build
12:35:44 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2
12:35:44 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
12:35:44 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
12:35:44 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
12:35:44 INFO - 'COMPUTERNAME': 'T-W732-IX-123',
12:35:44 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
12:35:44 INFO - 'CYGWINBASE': 'C:\\cygwin',
12:35:44 INFO - 'DCLOCATION': 'SCL3',
12:35:44 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
12:35:44 INFO - 'FP_NO_HOST_CHECK': 'NO',
12:35:44 INFO - 'HOMEDRIVE': 'C:',
12:35:44 INFO - 'HOMEPATH': '\\Users\\cltbld',
12:35:44 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
12:35:44 INFO - 'KTS_VERSION': '1.19c',
12:35:44 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
12:35:44 INFO - 'LOGONSERVER': '\\\\T-W732-IX-123',
12:35:44 INFO - 'MONDIR': 'C:\\Monitor_config\\',
12:35:44 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
12:35:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:35:44 INFO - 'MOZ_NO_REMOTE': '1',
12:35:44 INFO - 'NO_EM_RESTART': '1',
12:35:44 INFO - 'NUMBER_OF_PROCESSORS': '8',
12:35:44 INFO - 'OS': 'Windows_NT',
12:35:44 INFO - 'OURDRIVE': 'C:',
12:35:44 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
12:35:44 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
12:35:44 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
12:35:44 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
12:35:44 INFO - 'PROCESSOR_LEVEL': '6',
12:35:44 INFO - 'PROCESSOR_REVISION': '1e05',
12:35:44 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
12:35:44 INFO - 'PROGRAMFILES': 'C:\\Program Files',
12:35:44 INFO - 'PROMPT': '$P$G',
12:35:44 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
12:35:44 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
12:35:44 INFO - 'PUBLIC': 'C:\\Users\\Public',
12:35:44 INFO - 'PWD': 'C:\\slave\\test',
12:35:44 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
12:35:44 INFO - 'SLAVEDIR': 'C:\\slave\\',
12:35:44 INFO - 'SYSTEMDRIVE': 'C:',
12:35:44 INFO - 'SYSTEMROOT': 'C:\\windows',
12:35:44 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:35:44 INFO - 'TEST1': 'testie',
12:35:44 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:35:44 INFO - 'USERDOMAIN': 'T-W732-IX-123',
12:35:44 INFO - 'USERNAME': 'cltbld',
12:35:44 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
12:35:44 INFO - 'WINDIR': 'C:\\windows',
12:35:44 INFO - 'WINDOWS_TRACING_FLAGS': '3',
12:35:44 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
12:35:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
12:35:45 INFO - Ignoring indexes: https://pypi.python.org/simple/
12:35:45 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages
12:35:45 INFO - Cleaning up...
12:35:45 INFO - Return code: 0
12:35:45 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv
12:35: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')]}
12:35:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:35: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')]}
12:35:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:35:45 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 0x018CA640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01945E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017E39B0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0191EE90>, '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 0x0131A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EF5520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-123', 'USERDOMAIN': 'T-W732-IX-123', '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-123', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
12:35:45 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in C:\slave\test\build
12:35:45 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4
12:35:45 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
12:35:45 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
12:35:45 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
12:35:45 INFO - 'COMPUTERNAME': 'T-W732-IX-123',
12:35:45 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
12:35:45 INFO - 'CYGWINBASE': 'C:\\cygwin',
12:35:45 INFO - 'DCLOCATION': 'SCL3',
12:35:45 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
12:35:45 INFO - 'FP_NO_HOST_CHECK': 'NO',
12:35:45 INFO - 'HOMEDRIVE': 'C:',
12:35:45 INFO - 'HOMEPATH': '\\Users\\cltbld',
12:35:45 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
12:35:45 INFO - 'KTS_VERSION': '1.19c',
12:35:45 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
12:35:45 INFO - 'LOGONSERVER': '\\\\T-W732-IX-123',
12:35:45 INFO - 'MONDIR': 'C:\\Monitor_config\\',
12:35:45 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
12:35:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:35:45 INFO - 'MOZ_NO_REMOTE': '1',
12:35:45 INFO - 'NO_EM_RESTART': '1',
12:35:45 INFO - 'NUMBER_OF_PROCESSORS': '8',
12:35:45 INFO - 'OS': 'Windows_NT',
12:35:45 INFO - 'OURDRIVE': 'C:',
12:35:45 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
12:35:45 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
12:35:45 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
12:35:45 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
12:35:45 INFO - 'PROCESSOR_LEVEL': '6',
12:35:45 INFO - 'PROCESSOR_REVISION': '1e05',
12:35:45 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
12:35:45 INFO - 'PROGRAMFILES': 'C:\\Program Files',
12:35:45 INFO - 'PROMPT': '$P$G',
12:35:45 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
12:35:45 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
12:35:45 INFO - 'PUBLIC': 'C:\\Users\\Public',
12:35:45 INFO - 'PWD': 'C:\\slave\\test',
12:35:45 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
12:35:45 INFO - 'SLAVEDIR': 'C:\\slave\\',
12:35:45 INFO - 'SYSTEMDRIVE': 'C:',
12:35:45 INFO - 'SYSTEMROOT': 'C:\\windows',
12:35:45 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:35:45 INFO - 'TEST1': 'testie',
12:35:45 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:35:45 INFO - 'USERDOMAIN': 'T-W732-IX-123',
12:35:45 INFO - 'USERNAME': 'cltbld',
12:35:45 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
12:35:45 INFO - 'WINDIR': 'C:\\windows',
12:35:45 INFO - 'WINDOWS_TRACING_FLAGS': '3',
12:35:45 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
12:35:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
12:35:45 INFO - Ignoring indexes: https://pypi.python.org/simple/
12:35:45 INFO - Downloading/unpacking blobuploader==1.2.4
12:35:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
12:35:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
12:35:46 INFO - Downloading blobuploader-1.2.4.tar.gz
12:35:46 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader
12:35:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4)
12:35:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
12:35:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
12:35:46 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests
12:35:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4)
12:35:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
12:35:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
12:35:47 INFO - Downloading docopt-0.6.1.tar.gz
12:35:47 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt
12:35:47 INFO - Installing collected packages: blobuploader, requests, docopt
12:35:47 INFO - Running setup.py install for blobuploader
12:35:47 INFO - Running setup.py install for requests
12:35:48 INFO - Running setup.py install for docopt
12:35:48 INFO - Successfully installed blobuploader requests docopt
12:35:48 INFO - Cleaning up...
12:35:49 INFO - Return code: 0
12:35:49 INFO - Installing None into virtualenv C:\slave\test\build/venv
12:35:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:35:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:35:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:35:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:35:49 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 0x018CA640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01945E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017E39B0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0191EE90>, '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 0x0131A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EF5520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-123', 'USERDOMAIN': 'T-W732-IX-123', '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-123', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
12:35:49 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--no-deps', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test\build\tests\config
12:35:49 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --no-deps --timeout 120 -r C:\slave\test\build\tests\config\mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub
12:35:49 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
12:35:49 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
12:35:49 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
12:35:49 INFO - 'COMPUTERNAME': 'T-W732-IX-123',
12:35:49 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
12:35:49 INFO - 'CYGWINBASE': 'C:\\cygwin',
12:35:49 INFO - 'DCLOCATION': 'SCL3',
12:35:49 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
12:35:49 INFO - 'FP_NO_HOST_CHECK': 'NO',
12:35:49 INFO - 'HOMEDRIVE': 'C:',
12:35:49 INFO - 'HOMEPATH': '\\Users\\cltbld',
12:35:49 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
12:35:49 INFO - 'KTS_VERSION': '1.19c',
12:35:49 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
12:35:49 INFO - 'LOGONSERVER': '\\\\T-W732-IX-123',
12:35:49 INFO - 'MONDIR': 'C:\\Monitor_config\\',
12:35:49 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
12:35:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:35:49 INFO - 'MOZ_NO_REMOTE': '1',
12:35:49 INFO - 'NO_EM_RESTART': '1',
12:35:49 INFO - 'NUMBER_OF_PROCESSORS': '8',
12:35:49 INFO - 'OS': 'Windows_NT',
12:35:49 INFO - 'OURDRIVE': 'C:',
12:35:49 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
12:35:49 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
12:35:49 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
12:35:49 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
12:35:49 INFO - 'PROCESSOR_LEVEL': '6',
12:35:49 INFO - 'PROCESSOR_REVISION': '1e05',
12:35:49 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
12:35:49 INFO - 'PROGRAMFILES': 'C:\\Program Files',
12:35:49 INFO - 'PROMPT': '$P$G',
12:35:49 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
12:35:49 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
12:35:49 INFO - 'PUBLIC': 'C:\\Users\\Public',
12:35:49 INFO - 'PWD': 'C:\\slave\\test',
12:35:49 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
12:35:49 INFO - 'SLAVEDIR': 'C:\\slave\\',
12:35:49 INFO - 'SYSTEMDRIVE': 'C:',
12:35:49 INFO - 'SYSTEMROOT': 'C:\\windows',
12:35:49 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:35:49 INFO - 'TEST1': 'testie',
12:35:49 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:35:49 INFO - 'USERDOMAIN': 'T-W732-IX-123',
12:35:49 INFO - 'USERNAME': 'cltbld',
12:35:49 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
12:35:49 INFO - 'WINDIR': 'C:\\windows',
12:35:49 INFO - 'WINDOWS_TRACING_FLAGS': '3',
12:35:49 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
12:35:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
12:35:50 INFO - Ignoring indexes: https://pypi.python.org/simple/
12:35:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
12:35:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-0zcqfq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
12:35:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
12:35:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-m1rff0-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
12:35:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
12:35:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-v9jr6p-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
12:35:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
12:35:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-eehglu-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
12:35:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
12:35:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-s8ggsa-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
12:35:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
12:35:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-7lt6p9-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
12:35:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
12:35:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dod2bb-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
12:35:51 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
12:35:51 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-o3xoj_-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
12:35:51 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
12:35:51 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-uqfhyc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
12:35:51 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
12:35:51 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qyqghh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
12:35:51 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
12:35:51 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-u4dtz5-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
12:35:51 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
12:35:52 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rqt1ac-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
12:35:52 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
12:35:52 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-df7jxf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
12:35:52 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
12:35:52 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lg31cl-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
12:35:52 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
12:35:52 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pgpnhb-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
12:35:52 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
12:35:52 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pjdzjs-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
12:35:52 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
12:35:52 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mcwdzw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
12:35:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
12:35:52 INFO - Running setup.py install for manifestparser
12:35:53 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts
12:35:53 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts
12:35:53 INFO - Running setup.py install for mozcrash
12:35:53 INFO - Running setup.py install for mozdebug
12:35:54 INFO - Running setup.py install for mozdevice
12:35:54 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts
12:35:54 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts
12:35:54 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts
12:35:54 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts
12:35:54 INFO - Running setup.py install for mozfile
12:35:55 INFO - Running setup.py install for mozhttpd
12:35:55 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts
12:35:55 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts
12:35:55 INFO - Running setup.py install for mozinfo
12:35:56 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts
12:35:56 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts
12:35:56 INFO - Running setup.py install for mozInstall
12:35:56 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts
12:35:56 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts
12:35:56 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts
12:35:56 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts
12:35:56 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts
12:35:56 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts
12:35:56 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts
12:35:56 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts
12:35:56 INFO - Running setup.py install for mozleak
12:35:56 INFO - Running setup.py install for mozlog
12:35:57 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts
12:35:57 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts
12:35:57 INFO - Running setup.py install for moznetwork
12:35:57 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts
12:35:57 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts
12:35:58 INFO - Running setup.py install for mozprocess
12:35:58 INFO - Running setup.py install for mozprofile
12:35:58 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts
12:35:58 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts
12:35:58 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts
12:35:58 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts
12:35:58 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts
12:35:58 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts
12:35:59 INFO - Running setup.py install for mozrunner
12:35:59 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts
12:35:59 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts
12:35:59 INFO - Running setup.py install for mozscreenshot
12:36:00 INFO - Running setup.py install for moztest
12:36:00 INFO - Running setup.py install for mozversion
12:36:00 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts
12:36:00 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts
12:36:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion
12:36:01 INFO - Cleaning up...
12:36:01 INFO - Return code: 0
12:36:01 INFO - Installing None into virtualenv C:\slave\test\build/venv
12:36:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:36:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:36:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:36:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:36:01 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 0x018CA640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01945E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017E39B0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0191EE90>, '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 0x0131A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EF5520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-123', 'USERDOMAIN': 'T-W732-IX-123', '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-123', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
12:36:01 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test\build\tests\config
12:36:01 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 -r C:\slave\test\build\tests\config\mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub
12:36:01 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
12:36:01 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
12:36:01 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
12:36:01 INFO - 'COMPUTERNAME': 'T-W732-IX-123',
12:36:01 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
12:36:01 INFO - 'CYGWINBASE': 'C:\\cygwin',
12:36:01 INFO - 'DCLOCATION': 'SCL3',
12:36:01 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
12:36:01 INFO - 'FP_NO_HOST_CHECK': 'NO',
12:36:01 INFO - 'HOMEDRIVE': 'C:',
12:36:01 INFO - 'HOMEPATH': '\\Users\\cltbld',
12:36:01 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
12:36:01 INFO - 'KTS_VERSION': '1.19c',
12:36:01 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
12:36:01 INFO - 'LOGONSERVER': '\\\\T-W732-IX-123',
12:36:01 INFO - 'MONDIR': 'C:\\Monitor_config\\',
12:36:01 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
12:36:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:36:01 INFO - 'MOZ_NO_REMOTE': '1',
12:36:01 INFO - 'NO_EM_RESTART': '1',
12:36:01 INFO - 'NUMBER_OF_PROCESSORS': '8',
12:36:01 INFO - 'OS': 'Windows_NT',
12:36:01 INFO - 'OURDRIVE': 'C:',
12:36:01 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
12:36:01 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
12:36:01 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
12:36:01 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
12:36:01 INFO - 'PROCESSOR_LEVEL': '6',
12:36:01 INFO - 'PROCESSOR_REVISION': '1e05',
12:36:01 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
12:36:01 INFO - 'PROGRAMFILES': 'C:\\Program Files',
12:36:01 INFO - 'PROMPT': '$P$G',
12:36:01 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
12:36:01 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
12:36:01 INFO - 'PUBLIC': 'C:\\Users\\Public',
12:36:01 INFO - 'PWD': 'C:\\slave\\test',
12:36:01 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
12:36:01 INFO - 'SLAVEDIR': 'C:\\slave\\',
12:36:01 INFO - 'SYSTEMDRIVE': 'C:',
12:36:01 INFO - 'SYSTEMROOT': 'C:\\windows',
12:36:01 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:36:01 INFO - 'TEST1': 'testie',
12:36:01 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:36:01 INFO - 'USERDOMAIN': 'T-W732-IX-123',
12:36:01 INFO - 'USERNAME': 'cltbld',
12:36:01 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
12:36:01 INFO - 'WINDIR': 'C:\\windows',
12:36:01 INFO - 'WINDOWS_TRACING_FLAGS': '3',
12:36:01 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
12:36:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
12:36:01 INFO - Ignoring indexes: https://pypi.python.org/simple/
12:36:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
12:36:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lz2wqj-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
12:36:01 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///C:/slave/test/build/tests/mozbase/manifestparser in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 1))
12:36:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
12:36:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ct_9x6-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
12:36:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///C:/slave/test/build/tests/mozbase/mozcrash in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2))
12:36:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
12:36:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-_gsp7o-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
12:36:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///C:/slave/test/build/tests/mozbase/mozdebug in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 3))
12:36:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
12:36:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ajvvou-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
12:36:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///C:/slave/test/build/tests/mozbase/mozdevice in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4))
12:36:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
12:36:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lnrxe7-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
12:36:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///C:/slave/test/build/tests/mozbase/mozfile in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 5))
12:36:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
12:36:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-99dzni-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
12:36:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///C:/slave/test/build/tests/mozbase/mozhttpd in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 6))
12:36:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
12:36:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-oepvbz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
12:36:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///C:/slave/test/build/tests/mozbase/mozinfo in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 7))
12:36:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
12:36:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-b6_xy1-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
12:36:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///C:/slave/test/build/tests/mozbase/mozinstall in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 8))
12:36:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
12:36:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jjjsxk-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
12:36:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///C:/slave/test/build/tests/mozbase/mozleak in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 9))
12:36:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
12:36:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jj4my8-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
12:36:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///C:/slave/test/build/tests/mozbase/mozlog in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10))
12:36:03 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
12:36:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bnix7m-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
12:36:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///C:/slave/test/build/tests/mozbase/moznetwork in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 11))
12:36:03 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
12:36:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-nrxg43-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
12:36:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///C:/slave/test/build/tests/mozbase/mozprocess in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 12))
12:36:03 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
12:36:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jkd7x6-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
12:36:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///C:/slave/test/build/tests/mozbase/mozprofile in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 13))
12:36:03 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
12:36:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-askhly-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
12:36:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///C:/slave/test/build/tests/mozbase/mozrunner in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 14))
12:36:03 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
12:36:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ia_8c1-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
12:36:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///C:/slave/test/build/tests/mozbase/mozscreenshot in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 15))
12:36:03 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
12:36:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rscns_-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
12:36:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///C:/slave/test/build/tests/mozbase/moztest in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 16))
12:36:03 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
12:36:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-soxuiy-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
12:36:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///C:/slave/test/build/tests/mozbase/mozversion in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 17))
12:36:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==0.17->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2))
12:36:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==0.17->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2))
12:36:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in c:\slave\test\build\venv\lib\site-packages (from mozdebug==0.1->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 3))
12:36:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.48->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4))
12:36:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.48->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4))
12:36:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10))
12:36:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
12:36:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
12:36:05 INFO - Downloading blessings-1.6.tar.gz
12:36:05 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings
12:36:05 INFO - Installing collected packages: blessings
12:36:05 INFO - Running setup.py install for blessings
12:36:05 INFO - Successfully installed blessings
12:36:05 INFO - Cleaning up...
12:36:08 INFO - Return code: 0
12:36:08 INFO - Done creating virtualenv C:\slave\test\build/venv.
12:36:08 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
12:36:08 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
12:36:08 INFO - Reading from file tmpfile_stdout
12:36:08 INFO - Using _rmtree_windows ...
12:36:08 INFO - Using _rmtree_windows ...
12:36:08 INFO - Current package versions:
12:36:08 INFO - blessings == 1.6
12:36:08 INFO - blobuploader == 1.2.4
12:36:08 INFO - docopt == 0.6.1
12:36:08 INFO - functools32 == 3.2.3-2
12:36:08 INFO - jsonschema == 2.5.1
12:36:08 INFO - manifestparser == 1.1
12:36:08 INFO - mozInstall == 1.12
12:36:08 INFO - mozcrash == 0.17
12:36:08 INFO - mozdebug == 0.1
12:36:08 INFO - mozdevice == 0.48
12:36:08 INFO - mozfile == 1.2
12:36:08 INFO - mozhttpd == 0.7
12:36:08 INFO - mozinfo == 0.9
12:36:08 INFO - mozleak == 0.1
12:36:08 INFO - mozlog == 3.4
12:36:08 INFO - moznetwork == 0.27
12:36:08 INFO - mozprocess == 0.23
12:36:08 INFO - mozprofile == 0.28
12:36:08 INFO - mozrunner == 6.12
12:36:08 INFO - mozscreenshot == 0.1
12:36:08 INFO - mozsystemmonitor == 0.3
12:36:08 INFO - moztest == 0.7
12:36:08 INFO - mozversion == 1.4
12:36:08 INFO - psutil == 3.1.1
12:36:08 INFO - requests == 1.2.3
12:36:08 INFO - Installing None into virtualenv C:\slave\test\build/venv
12:36:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:36:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:36:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:36:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:36:08 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 0x018CA640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01945E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017E39B0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0191EE90>, '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 0x0131A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EF5520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-123', 'USERDOMAIN': 'T-W732-IX-123', '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-123', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
12:36:08 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\talos\\requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test\build\tests\talos
12:36:08 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 -r C:\slave\test\build\tests\talos\requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub
12:36:08 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
12:36:08 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
12:36:08 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
12:36:08 INFO - 'COMPUTERNAME': 'T-W732-IX-123',
12:36:08 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
12:36:08 INFO - 'CYGWINBASE': 'C:\\cygwin',
12:36:08 INFO - 'DCLOCATION': 'SCL3',
12:36:08 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
12:36:08 INFO - 'FP_NO_HOST_CHECK': 'NO',
12:36:08 INFO - 'HOMEDRIVE': 'C:',
12:36:08 INFO - 'HOMEPATH': '\\Users\\cltbld',
12:36:08 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
12:36:08 INFO - 'KTS_VERSION': '1.19c',
12:36:08 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
12:36:08 INFO - 'LOGONSERVER': '\\\\T-W732-IX-123',
12:36:08 INFO - 'MONDIR': 'C:\\Monitor_config\\',
12:36:08 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
12:36:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:36:08 INFO - 'MOZ_NO_REMOTE': '1',
12:36:08 INFO - 'NO_EM_RESTART': '1',
12:36:08 INFO - 'NUMBER_OF_PROCESSORS': '8',
12:36:08 INFO - 'OS': 'Windows_NT',
12:36:08 INFO - 'OURDRIVE': 'C:',
12:36:08 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
12:36:08 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
12:36:08 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
12:36:08 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
12:36:08 INFO - 'PROCESSOR_LEVEL': '6',
12:36:08 INFO - 'PROCESSOR_REVISION': '1e05',
12:36:08 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
12:36:08 INFO - 'PROGRAMFILES': 'C:\\Program Files',
12:36:08 INFO - 'PROMPT': '$P$G',
12:36:08 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
12:36:08 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
12:36:08 INFO - 'PUBLIC': 'C:\\Users\\Public',
12:36:08 INFO - 'PWD': 'C:\\slave\\test',
12:36:08 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
12:36:08 INFO - 'SLAVEDIR': 'C:\\slave\\',
12:36:08 INFO - 'SYSTEMDRIVE': 'C:',
12:36:08 INFO - 'SYSTEMROOT': 'C:\\windows',
12:36:08 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:36:08 INFO - 'TEST1': 'testie',
12:36:08 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:36:08 INFO - 'USERDOMAIN': 'T-W732-IX-123',
12:36:08 INFO - 'USERNAME': 'cltbld',
12:36:08 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
12:36:08 INFO - 'WINDIR': 'C:\\windows',
12:36:08 INFO - 'WINDOWS_TRACING_FLAGS': '3',
12:36:08 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
12:36:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
12:36:09 INFO - Ignoring indexes: https://pypi.python.org/simple/
12:36:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 1))
12:36:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 2))
12:36:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 3))
12:36:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 4))
12:36:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 5))
12:36:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 6))
12:36:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 7))
12:36:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 8))
12:36:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 9))
12:36:09 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in c:\slave\test\build\venv\lib\site-packages (from mozlog>=3.1->-r C:\slave\test\build\tests\talos\requirements.txt (line 1))
12:36:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test\build\venv\lib\site-packages (from mozhttpd>=0.7->-r C:\slave\test\build\tests\talos\requirements.txt (line 4))
12:36:09 INFO - Cleaning up...
12:36:09 INFO - Return code: 0
12:36:09 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv
12:36:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:36:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:36:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:36:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:36:09 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018CA640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01945E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017E39B0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0191EE90>, '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 0x0131A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EF5520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-123', 'USERDOMAIN': 'T-W732-IX-123', '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-123', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
12:36:09 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in C:\slave\test\build
12:36:09 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema
12:36:09 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
12:36:09 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
12:36:09 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
12:36:09 INFO - 'COMPUTERNAME': 'T-W732-IX-123',
12:36:09 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
12:36:09 INFO - 'CYGWINBASE': 'C:\\cygwin',
12:36:09 INFO - 'DCLOCATION': 'SCL3',
12:36:09 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
12:36:09 INFO - 'FP_NO_HOST_CHECK': 'NO',
12:36:09 INFO - 'HOMEDRIVE': 'C:',
12:36:09 INFO - 'HOMEPATH': '\\Users\\cltbld',
12:36:09 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
12:36:09 INFO - 'KTS_VERSION': '1.19c',
12:36:09 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
12:36:09 INFO - 'LOGONSERVER': '\\\\T-W732-IX-123',
12:36:09 INFO - 'MONDIR': 'C:\\Monitor_config\\',
12:36:09 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
12:36:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:36:09 INFO - 'MOZ_NO_REMOTE': '1',
12:36:09 INFO - 'NO_EM_RESTART': '1',
12:36:09 INFO - 'NUMBER_OF_PROCESSORS': '8',
12:36:09 INFO - 'OS': 'Windows_NT',
12:36:09 INFO - 'OURDRIVE': 'C:',
12:36:09 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
12:36:09 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
12:36:09 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
12:36:09 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
12:36:09 INFO - 'PROCESSOR_LEVEL': '6',
12:36:09 INFO - 'PROCESSOR_REVISION': '1e05',
12:36:09 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
12:36:09 INFO - 'PROGRAMFILES': 'C:\\Program Files',
12:36:09 INFO - 'PROMPT': '$P$G',
12:36:09 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
12:36:09 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
12:36:09 INFO - 'PUBLIC': 'C:\\Users\\Public',
12:36:09 INFO - 'PWD': 'C:\\slave\\test',
12:36:09 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
12:36:09 INFO - 'SLAVEDIR': 'C:\\slave\\',
12:36:09 INFO - 'SYSTEMDRIVE': 'C:',
12:36:09 INFO - 'SYSTEMROOT': 'C:\\windows',
12:36:09 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:36:09 INFO - 'TEST1': 'testie',
12:36:09 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:36:09 INFO - 'USERDOMAIN': 'T-W732-IX-123',
12:36:09 INFO - 'USERNAME': 'cltbld',
12:36:09 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
12:36:09 INFO - 'WINDIR': 'C:\\windows',
12:36:09 INFO - 'WINDOWS_TRACING_FLAGS': '3',
12:36:09 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
12:36:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
12:36:09 INFO - Ignoring indexes: https://pypi.python.org/simple/
12:36:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages
12:36:09 INFO - Cleaning up...
12:36:09 INFO - Return code: 0
12:36:09 INFO - Running post-action listener: _resource_record_post_action
12:36:09 INFO - Running post-action listener: _start_resource_monitoring
12:36:09 INFO - Starting resource monitoring.
12:36:09 INFO - [mozharness: 2016-09-22 19:36:09.725000Z] Finished create-virtualenv step (success)
12:36:09 INFO - [mozharness: 2016-09-22 19:36:09.725000Z] Running install step.
12:36:09 INFO - Running pre-action listener: _resource_record_pre_action
12:36:09 INFO - Running main action method: install
12:36:09 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
12:36:09 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
12:36:10 INFO - Reading from file tmpfile_stdout
12:36:10 INFO - Using _rmtree_windows ...
12:36:10 INFO - Using _rmtree_windows ...
12:36:10 INFO - Detecting whether we're running mozinstall >=1.0...
12:36:10 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h']
12:36:10 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h
12:36:10 INFO - Reading from file tmpfile_stdout
12:36:10 INFO - Output received:
12:36:10 INFO - Usage: mozinstall-script.py [options] installer
12:36:10 INFO - Options:
12:36:10 INFO - -h, --help show this help message and exit
12:36:10 INFO - -d DEST, --destination=DEST
12:36:10 INFO - Directory to install application into. [default:
12:36:10 INFO - "C:\slave\test"]
12:36:10 INFO - --app=APP Application being installed. [default: firefox]
12:36:10 INFO - Using _rmtree_windows ...
12:36:10 INFO - Using _rmtree_windows ...
12:36:10 INFO - mkdir: C:\slave\test\build\application
12:36:10 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.zip', '--destination', 'C:\\slave\\test\\build\\application']
12:36:10 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py C:\slave\test\build\firefox-52.0a1.en-US.win32.zip --destination C:\slave\test\build\application
12:36:12 INFO - Reading from file tmpfile_stdout
12:36:12 INFO - Output received:
12:36:12 INFO - C:\slave\test\build\application\firefox\firefox.exe
12:36:12 INFO - Using _rmtree_windows ...
12:36:12 INFO - Using _rmtree_windows ...
12:36:12 INFO - Running post-action listener: _resource_record_post_action
12:36:12 INFO - [mozharness: 2016-09-22 19:36:12.507000Z] Finished install step (success)
12:36:12 INFO - [mozharness: 2016-09-22 19:36:12.507000Z] Running run-tests step.
12:36:12 INFO - Running pre-action listener: _resource_record_pre_action
12:36:12 INFO - Running main action method: run_tests
12:36:12 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version']
12:36:12 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version
12:36:12 INFO - Python 2.7.3
12:36:12 INFO - Return code: 0
12:36:12 INFO - grabbing minidump binary from tooltool
12:36:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:36:12 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 0x0191EE90>, '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 0x0131A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EF5520>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1
12:36:12 INFO - Running command: ['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'] in C:\slave\test\build
12:36:12 INFO - Copy/paste: c:/mozilla-build/python27/python.exe C:/mozilla-build/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file c:\builds\relengapi.tok fetch -m C:\slave\test\build\tests\config/tooltool-manifests/win32/releng.manifest -o
12:36:12 INFO - Calling ['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'] with output_timeout 600
12:36:12 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'...
12:36:14 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmporlaje
12:36:14 INFO - INFO - File integrity verified, renaming tmporlaje to win32-minidump_stackwalk.exe
12:36:14 INFO - Return code: 0
12:36:14 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755
12:36:14 INFO - mkdir: C:\slave\test\build\blobber_upload_dir
12:36:14 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir
12:36:14 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe
12:36:14 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir
12:36:14 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/La_s1kTOTIm9u6v6rE6Udw/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-123', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs_errorsummary.log', '--log-raw=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs_raw.log'] in C:\slave\test\build
12:36:14 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --branchName Autoland-Non-PGO --suite dromaeojs --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/La_s1kTOTIm9u6v6rE6Udw/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-123 --webServer localhost --log-tbpl-level=debug --log-errorsummary=C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log --log-raw=C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log
12:36:14 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
12:36:14 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
12:36:14 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
12:36:14 INFO - 'COMPUTERNAME': 'T-W732-IX-123',
12:36:14 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
12:36:14 INFO - 'CYGWINBASE': 'C:\\cygwin',
12:36:14 INFO - 'DCLOCATION': 'SCL3',
12:36:14 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
12:36:14 INFO - 'FP_NO_HOST_CHECK': 'NO',
12:36:14 INFO - 'HOMEDRIVE': 'C:',
12:36:14 INFO - 'HOMEPATH': '\\Users\\cltbld',
12:36:14 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
12:36:14 INFO - 'KTS_VERSION': '1.19c',
12:36:14 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
12:36:14 INFO - 'LOGONSERVER': '\\\\T-W732-IX-123',
12:36:14 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir',
12:36:14 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe',
12:36:14 INFO - 'MONDIR': 'C:\\Monitor_config\\',
12:36:14 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
12:36:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:36:14 INFO - 'MOZ_NO_REMOTE': '1',
12:36:14 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir',
12:36:14 INFO - 'NO_EM_RESTART': '1',
12:36:14 INFO - 'NUMBER_OF_PROCESSORS': '8',
12:36:14 INFO - 'OS': 'Windows_NT',
12:36:14 INFO - 'OURDRIVE': 'C:',
12:36:14 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
12:36:14 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
12:36:14 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
12:36:14 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
12:36:14 INFO - 'PROCESSOR_LEVEL': '6',
12:36:14 INFO - 'PROCESSOR_REVISION': '1e05',
12:36:14 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
12:36:14 INFO - 'PROGRAMFILES': 'C:\\Program Files',
12:36:14 INFO - 'PROMPT': '$P$G',
12:36:14 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
12:36:14 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
12:36:14 INFO - 'PUBLIC': 'C:\\Users\\Public',
12:36:14 INFO - 'PWD': 'C:\\slave\\test',
12:36:14 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos',
12:36:14 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
12:36:14 INFO - 'SLAVEDIR': 'C:\\slave\\',
12:36:14 INFO - 'SYSTEMDRIVE': 'C:',
12:36:14 INFO - 'SYSTEMROOT': 'C:\\windows',
12:36:14 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:36:14 INFO - 'TEST1': 'testie',
12:36:14 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:36:14 INFO - 'USERDOMAIN': 'T-W732-IX-123',
12:36:14 INFO - 'USERNAME': 'cltbld',
12:36:14 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
12:36:14 INFO - 'WINDIR': 'C:\\windows',
12:36:14 INFO - 'WINDOWS_TRACING_FLAGS': '3',
12:36:14 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
12:36:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
12:36:14 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/La_s1kTOTIm9u6v6rE6Udw/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-123', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs_errorsummary.log', '--log-raw=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs_raw.log'] with output_timeout 3600
12:36:15 INFO - mozversion application_buildid: 20160922113659
12:36:15 INFO - mozversion application_changeset: 70c6f01a830b3a39eb8ac1abf828312de48579aa
12:36:15 INFO - mozversion application_display_name: Nightly
12:36:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
12:36:15 INFO - mozversion application_name: Firefox
12:36:15 INFO - mozversion application_remotingname: firefox
12:36:15 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland
12:36:15 INFO - mozversion application_vendor: Mozilla
12:36:15 INFO - mozversion application_version: 52.0a1
12:36:15 INFO - mozversion platform_buildid: 20160922113659
12:36:15 INFO - mozversion platform_changeset: 70c6f01a830b3a39eb8ac1abf828312de48579aa
12:36:15 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland
12:36:15 INFO - mozversion platform_version: 52.0a1
12:36:15 INFO - using testdate: 1474572975
12:36:15 INFO - actual date: 1474572975
12:36:15 INFO - starting webserver on 'localhost:49609'
12:36:15 INFO - SUITE-START | Running 2 tests
12:36:15 INFO - TEST-START | dromaeo_css
12:36:15 INFO - operating with platform_type : w7_
12:36:15 INFO - Initialising browser for dromaeo_css test...
12:36:15 INFO - TEST-INFO | started process 2800 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmp0n_gnj\profile http://localhost:49609/getInfo.html)
12:36:23 INFO - PROCESS | 2800 | __metrics Screen width/height:1600/1200
12:36:23 INFO - PROCESS | 2800 | colorDepth:24
12:36:23 INFO - PROCESS | 2800 | Browser inner width/height: 1010/674
12:36:23 INFO - PROCESS | 2800 | __metrics
12:36:24 INFO - PROCESS | 2800 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows"
12:36:24 INFO - PROCESS | 2800 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
12:36:25 INFO - TEST-INFO | 2800: exit 0
12:36:25 INFO - Browser initialized.
12:36:25 INFO - Running cycle 1/1 for dromaeo_css test...
12:36:25 INFO - TEST-INFO | started process 3668 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmp0n_gnj\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1)
12:36:25 INFO - PROCESS | 3668 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0
12:36:37 INFO - PROCESS | 3668 | RSS: Main: 139165696
12:36:37 INFO - PROCESS | 3668 |
12:37:28 INFO - PROCESS | 3668 | 1474573048382 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no]
12:39:40 INFO - PROCESS | 3668 | Cycle 1(1): loaded http://localhost:49609/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49609/tests/dromaeo/cssquery-ext.html)
12:39:41 INFO - PROCESS | 3668 | RSS: Main: 190177280
12:39:41 INFO - PROCESS | 3668 |
12:42:44 INFO - PROCESS | 3668 | Cycle 1(1): loaded http://localhost:49609/tests/dromaeo/cssquery-ext.html (next: http://localhost:49609/tests/dromaeo/cssquery-jquery.html)
12:42:45 INFO - PROCESS | 3668 | RSS: Main: 200048640
12:42:45 INFO - PROCESS | 3668 |
12:45:47 INFO - PROCESS | 3668 | Cycle 1(1): loaded http://localhost:49609/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49609/tests/dromaeo/cssquery-mootools.html)
12:45:48 INFO - PROCESS | 3668 | RSS: Main: 154587136
12:45:48 INFO - PROCESS | 3668 |
12:48:50 INFO - PROCESS | 3668 | Cycle 1(1): loaded http://localhost:49609/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49609/tests/dromaeo/cssquery-prototype.html)
12:48:51 INFO - PROCESS | 3668 | RSS: Main: 137109504
12:48:51 INFO - PROCESS | 3668 |
12:51:54 INFO - PROCESS | 3668 | Cycle 1(1): loaded http://localhost:49609/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49609/tests/dromaeo/cssquery-yui.html)
12:51:54 INFO - PROCESS | 3668 | RSS: Main: 136564736
12:51:54 INFO - PROCESS | 3668 |
12:54:57 INFO - PROCESS | 3668 | Cycle 1(1): loaded http://localhost:49609/tests/dromaeo/cssquery-yui.html (next: http://localhost:49609/tests/dromaeo/cssquery-dojo.html)
12:54:57 INFO - PROCESS | 3668 | RSS: Main: 187965440
12:54:57 INFO - PROCESS | 3668 |
12:54:57 INFO - PROCESS | 3668 | __start_tp_report
12:54:57 INFO - PROCESS | 3668 | _x_x_mozilla_page_load
12:54:57 INFO - PROCESS | 3668 | _x_x_mozilla_page_load_details
12:54:57 INFO - PROCESS | 3668 | |i|pagename|runs|
12:54:57 INFO - PROCESS | 3668 | |0;dojo.html;2231.263682984615;2278.646809744489;2301.8388712790106;2331.2423462374727;2357.3752955466794;3921.803909804509;3929.783861887595;3936.409538569209;3989.780562069089;4009.799510024498;3788.2991646545406;3793.9430908536324;3903.8438462461468;3904.629060239273;4028.174224284022;3380.4084285250046;3449.689527942485;3460.2387474755515;3491.301739652067;3521.295740851827;3497.9475307870403;3500.439929611263;3526.312369088029;3548.0774998500447;3563.127033876704;3493.755437119403;3495.8951231463097;3523.9119022024397;3540.4689296605457;3547.3260080584605;3378.2905589826164;3399.473081672345;3425.5889293284963;3440.913977150566;3467.6185619581825;3343.732501399882;3475.3396854597545;3523.1720545671747;3532.0993146747674;3535.5580552430947;3905.8242379092726;3913.941290880639;3967.206558688274;3974.980125099356;3975.20495900821;3945.585713500099;4010.639042486161;4063.451434056394;4141.151064031867;4171.499420069581;4094.4267802507675;4119.546849846514;4219.198352313081;4228.619424251832;4233.534311225762;3849.749766265184;3868.5357757069332;3912.39357899526;3935.6654684351865;3944.3886197639413;4080.7959602019782;4132.090939993197;4191.434156388848;4206.074663574009;4299.720518166309;4047.959520404758;4081.836726530965;4137.834486620562;4139.317012692872;4142.482189726285;4028.154087641628;4182.874513764592;4191.224623444673;4197.622214000755;4201.390798334285;3928.332183528807;3944.447777311179;4086.591340865949;4157.64660003897;4169.478815148106;3929.646331830149;3973.8410463581713;4000.2999475092324;4134.152498737752;4144.295469770146;2904.564315352714;2992.311768293261;2993.0422264875033;2994.520876659724;3003.174127115017;2527.36815796051;2646.827956182842;2680.343315887581;2737.7125401832523;2738.3017330580574;3781.3949768036982;3790.1093243087853;3806.390977443651;3809.580946095927;3859.4789703390243;4128.401381799622;4165.208610364021;4209.536950935395;4209.915801683949;4215.683823713233;3807.3146833569667;3853;3860;3897;3930.9017274568364;3412.3004784019217;3417.128632198773;3447.207142357272;3526.717862570988;3563.3585954528435;4145.17096580679;4260.062786187033;4307.870763877088;4308.461442319647;4313.892152696208;4139.81370838319;4152.6055024772595;4153.210889930903;4209.34755112958;4211.157768446262;4107.958920410758;4156.127213285244;4233.132207897312;4300.913981720286;4308.806103725277;4094.6928980325997;4116.9794151029055;4148.522919864255;4166.749995000309;4171.812268447988;4087;4125.690573206962;4185.937210941899;4207.11650553375;4207.179599977975;3866.0141663876657;3932.9606703932595;3970.8213130409777;3998.1204135090234;4085.0604360996545;3995.4006898965386;4010.318245898263;4027.17442924194;4031.334829753175;4046.170535040251;3869.8645547405704;3915.530136383652;3987.342088555472;3992.9817896436225;3993.281209382339;3873.9806300968316;3900.083480382051;4002.6997975152563;4071.653909417733;4077.8164982575263;3967.9404808928466;3998.640122388999;3999.7800120993625;4009.8997525062105;4038.1116154445976
12:54:57 INFO - PROCESS | 3668 | |1;ext.html;547.8356493052086;555.6416111608011;558.9217509548664;565.7482420322957;575.5309422820399;26855.194344169682;28096.157115286547;28107;36515.634843651555;36543.269134617294;37125.62874371256;37847.86456406306;38389;38398;38422.8078859606;7649.426293028017;7865;7870.3310218631195;7891.763247102596;7908.327792137675;6426.839329016789;6500.6099634021875;6600.141981542392;7692.576908270043;7784.805379865492;6696.698648560802;6767.49243806714;9829.459379734111;10107;10117.342372745748;9970.102690757867;10082.39505629661;10106.848397274047;10139.138173255282;10166.89833101667;9883.209343252522;9932.304738668296;9950;9965.55155018026;10017.098461138532;25256.242312730647;25766.227013189633;34337.48493772595;34991.30017399638;35034;2757.986210068957;2788.177487641151;2795.7630911532233;2802.7057158998214;2862.6994165612728;26971.46057078867;27048.59427108595;27205;27496.312592185157;27925.581116283272;3211.14904550292;3276.1154488288266;3318.6515415881227;3373.1061268763797;3437.570303712036;216336;219120;220087;220645;220807;46914;47007.29489057667;47037.29444058342;47410.762946185045;47441.28838067432;44995.550044499134;45839;47561.524384756405;47599.28601070987;48689;9762.804743905224;9847.359921605073;9926.602935882629;9954.45250511229;10009.949950250202;6768.69540870662;6781.287964763629;6793.184817821794;6804.489663275274;6851.9657401712675;12780.297083660487;15263.694726105416;15282.465113720966;15299.617509562127;15406.152661603495;4894.651862778337;5793.478586927196;6078.726457309432;6204.162438070893;6244.75020999164;744.7244519527701;770.8959290495824;781.6248200863618;826.2481142160605;868.2576397180446;24975.87512062428;25617.231483055537;25791.93664506835;25859.870700646377;25867.74132258691;860.4966094834467;884.1644645809648;903.2818908967255;921.2077613252598;952.2096659772373;12937.223766574036;13519.729605407836;13647;13723.931380343034;13758.380872860545;281044;301593;309148;309848;314208;44026.11947761027;44753.32870006953;44871.77564112159;44939;44939.32591011137;964.7009427077628;996.2279233594022;1074.425182527344;1078.9892101078888;1080.2492267873752;23813.761862381154;26215;26349.20952371432;26392.604110938737;26630.334241644116;37992;38059.2388152241;38227;38310.80844595759;39162.80418597889;252028;259889;260888;261382;261736;20749.89625051865;21323.253686120912;21461.892690536446;22542.323730288117;22587.66118508191;38216;38243.36688014634;38948.2210355797;38949.83150505489;39365.21269574535;32525;33037.87912873508;33348.16629584183;33437.83281083579;34332;32438.35123297568;32745.836270818494;33866.830665846515;34226.657733423344;34354.312913742084;31677.8416107918;32633.18417039593;32916.670833292315;32979.34041319112;33048.834755826065;28736.712632873405;29857.25356866096;30537.694623053485;30901.381972360876;31324.686753132177;22459;23646;23972.160974365812;24082.87958560266;24196.75803241945
12:54:57 INFO - PROCESS | 3668 | |2;jquery.html;277.3772879884659;304.48693950693075;307.1645125259294;309.5789725972678;316.09438957387096;11456.792098212492;12346.753064938706;16125.596860078489;16368.754468682979;16698.66602667946;14898.553043408689;15019.849801501981;15514.379424823022;15527.922360388182;15670.921645391758;4858.708477491362;5049.84850454487;5065.290859279704;5067.594592432597;5069.594432445396;3523.6124026357206;3634.309481198577;3694;4993.550580447759;5072.213806859924;3692.612275711052;3919.647231749142;5625.38120806711;5798.449147330997;5909.4090590941;5867.266591676041;5886.264216972879;5896.351401345849;5900.144479050535;5902;4364.061726728769;4675.064987002579;4714.363560919266;4731.905361892777;5089.109405853961;10822.67531974042;10890.945545272301;10926;10990.560377584887;10998;1623.4399132299338;1672.5651330654118;1901.895395753233;1964.027806235908;2199.978000219993;8397.53813540255;8437.240648341678;8468.83062338756;8496.660133594649;8580.356473264468;1511.7203317386318;1546.9226538673022;1570.9396157593578;1596.8163661178996;1600.8559229669386;63029.36970630281;64153.35846641521;64169.358306416805;65991;68248;8621.396502244783;8627;8693.130686931383;8965.103489650983;9006.549672516348;10359.119474844572;10759.246852720235;10972;11661;11892.583759568546;5727.312722473246;5846.29844418664;5867.149263356875;5919.496842768327;5919.911201331984;5245.291885595396;5328.653637513549;5372.274742909657;5440.483154100354;5455.754491047913;3759.605241449663;3809.066778639251;3830.5020347354666;3860.745257791229;3868.129670824042;1546.373718643951;1571.4892659885584;1589.4675283780064;1589.9443519476763;1612.5484864238033;769.3806485779002;780.2899361580934;789.4158322841056;817.8691409374471;853.7353420439683;15987.120708361152;16250.75623865643;16453.341866325212;16623.418180363627;16893.577660558585;773.5668025905511;787.4330482052912;841.0328122658868;856.9807602344982;895.6283142495921;8644.3948923576;9104.681336153202;9885.654002109892;9900.455474948947;10003;270033;274924;276915;277191;278747;72862.63568682123;73915;75173;75495.24504754992;76141;792.2434075457924;810.9189081091962;811.5901469757735;815.5595978171721;817.1011886924455;14804.555863324118;15174.772378414553;15192.620184495476;15289.6177595561;15367.385304587691;37430.251394972496;37755.43366849444;37852.432213516266;38699.41950870796;38768.418473722355;49627.75186124046;49922.75038624783;50945.490545095556;51680.74159629178;52120.478795211566;6683.627312304062;6702.83242918931;6931.965340173267;6935.063766391472;6957.199922008831;10024.498775061364;10133.13868321201;10443.634472793416;10756.978087081714;11070.335779853234;8503.489790612583;8651.178138076873;8682.218600325748;8793.20861122502;8936.776580585538;9232.676856310265;9252.583633736363;9278.257739380699;9361.204297634777;9560.23518118535;8022.9598852005365;8616.439931404439;8668.609912554077;8717.825643487222;9064.637414503346;6608.603483790988;6661.666916654051;6726.781153062854;6843.726250949906;6866.485013623898;6628.635425051669;8675.956620217112;8842.867356989778;8916.465012099296;8968.461892286483
12:54:57 INFO - PROCESS | 3668 | |3;mootools.html;1076.983845242321;1207.7041124924394;1213.5691829400562;1260.8171815086812;1530.7244695954723;5378.0857254266775;6551.082848401222;6755.459563234942;6760.087388202591;6917.13535808024;6332.778352757655;6415.166028416299;6737.865242695143;6783.525153239275;6785.9481780324;1793.8654600904886;1803.6843552378348;1819.3814103204975;1823.7082066869236;1827.2142978519232;1757.042411885519;1806.7018941874642;1811.3931832836015;1818.990905045473;1819.0813639112296;1823.5714607067328;1823.726441033849;1828.0676854804012;1828.8536917046672;1835.843953263974;1707.7865266841645;1710.2560386231967;1747.0303981290378;1748.6764948484572;1749.8862573932654;1711.144427786107;1719.5443207550009;1749.2827940544444;1750.1599232368535;1751.9649607007916;6466.805995820133;6599.670016499156;6682.365175308338;6698;6698.296678848699;3696.3900956342172;3836.6163383661687;3853.0560012797046;3854.8650797222244;3855.8072096395067;2408.2895545813853;2411.9397015074587;2448.755124487555;2449.7672721091467;2450.3139121045956;736.4219088015882;743.3718507860849;778.5056489129387;782.3193821375382;784.3960150683941;8051.953246078031;8102.068262149869;8211.95894020532;8237.176282371714;8264.83470330596;3165.382750363657;3166.4142133705336;3234.062121984598;3236.660150684191;3258.3809076275434;6602.537822352485;6605.735770569219;6615.768448104293;6629.270780214172;6646.302138275411;3522.313148935933;3639.162992511684;3689.889303320851;3700.3524383232825;3714.4799728038097;2915.8687859046395;3027.5307327364294;3363.973987933655;3395.9830200848837;3442.345954268681;2776.278167676419;2887.740103390663;2907.389448215856;2907.8400687962367;2941.102963596081;860.1054902900913;865.4893612768371;877.6928075173638;878.2578720980761;883.2801266967401;713.630844710963;721.7401735375261;726.1358982810439;733.5085492719891;737.5537799631253;4164.146349998243;4164.333706606928;4198.067776751741;4274.508431530352;4345.456817897762;728.2026181331433;757.3865169212955;766.180187199691;768.4659161882438;781.82408957984;2829.4765468388414;2927.809692369989;2932.2376182192784;2946.57274695168;3009.6087508623737;7644.541327520366;7776.572288524072;7959.880601790977;7969.083555391088;8044.758657240292;7571.205023472567;7981.441299109007;7982.481138725964;7984.281414672707;8000.799980000546;6112.2665280165775;6199.070139479114;6253.155823963798;6256.249250089927;6295.716692748837;4224.1340525191645;4224.324108142805;4226.344916537942;4291.828326867016;4347.695661303678;7469.178390377163;7505.924940750741;7506.624668766431;7513.511621744569;7524.586147761706;7254.202037775735;7589.051368578927;7631.3513351365755;7672.923270767445;7673.812963305431;5328.733563321741;5336.546393556591;5343.145096784496;5453.672779633234;5466.480684334982;5307.814226502208;5346.97326513365;6146.907796383147;6222.0355844844125;6256.217972753406;5583.832485025293;5611.232436852458;5712.514436272797;5713.628614140068;5758.596898217253;5297.125974214366;5547.944520554743;5583.357913839798;5594.9720251398485;5598.888022239451;4769.66612337133;4989.101961646938;5018.071656743514;5039.21892106724;5047.167217409086;3293.967060329366;3462.584489861132;3485.3552092861887;3506.4564992426212;3556.2531868307947;3493.7729047611824;3510.315488479722;3515.2442224921765;3530.3468858261303;3547.9822600886832
12:54:57 INFO - PROCESS | 3668 | |4;prototype.html;230.6862666773189;243.8427214446682;243.8951250962086;246.40493208900494;250.71543797789514;4251.192273468039;4297.806598703058;4318.373835793812;4320.546342634021;4321.54623764505;4066.207089617526;4112.57358158687;4187.22536330779;4258.276093064179;4266.8933276668095;3222.194451387153;3226.887058952943;3238.449463591195;3246.529253258276;3250.1874531367157;3234.7250483708913;3241.254511462365;3243.1567792374035;3247.480403135499;3250.626177989526;2997.685243049481;3094.226443389153;3099.736522395599;3224.274538228903;3226.8383381982467;3124.53132030195;3192.6807319267887;3218.4367735646283;3224.500202468621;3226.354729054175;2854.557543580748;2865.8137221080565;2873.8073699414717;2875.52553828618;2877;4184.372344148372;4185.707000509966;4187.173657781016;4190.182914331706;4199.727017743837;3762.194146809547;3793.696504279651;3797.3354662933875;3797.8480860765535;3799.3351163546545;4290.871273861789;4410.2723050696595;4419.138268037734;4433.534478879704;4436.778161091933;3197.216681912923;3199.752096682296;3207.1501052220947;3207.486802111651;3211.5985501812274;4480.462344518679;4592.7014744041535;4597.264437689953;4609.976950115228;4610.400647915783;3287.3589650018494;3291.522729204252;3291.6708329167373;3296;3298.835058247078;3315.386653469116;3316.706484471058;3324.2852786650988;3337.3825842219267;3338.6494418086236;3643.2349206666368;3758.229562939591;3771.358868992278;3772.396416573335;3778.7732736035928;4385.320275357325;4401.867943961686;4414.3157810539415;4425;4434.955650443454;1129.2546919033398;1135.8977692007593;1142.3831131189231;1149.6378640728144;1151.842398389613;695.1276148433784;712.1098626716604;741.3550211316082;778.8481246157058;796.1123347467532;4131.9173616528105;4141.834326626961;4188.518320393133;4212.347086201644;4213.2837417639075;4415.40392047076;4491.30384790358;4529.501754806968;4540.364348991144;4546.431696037996;4129.380592911088;4138.772367519815;4155.397467367215;4207.936880946788;4208.389783481378;2315.9073637054667;2361.2916125162383;2398.8800559972283;2418.226167626378;2423.382037580441;4674.312270444508;4685.226937555265;4686.531346865354;4724.6456515760765;4736.64475164364;4719.197736384823;4724.763761811896;4725.07860967115;4741.075490279362;4746.406699162605;4662.137504561667;4718.598919091847;4732.976335118371;4739.265413860857;4742.596879265232;4463.553644635511;4508.594226519629;4600.723956562617;4634.675572709878;4645.256758918692;4436.423264975532;4504.954950450454;4522.163399771053;4523.5250298718165;4525.841595544141;4614.884627884329;4622.168009758276;4688.5545873142;4699.976500117477;4717.033008233372;3267.934641307208;3269.378818024568;3283.688049635281;3298.752593555445;3313.337332533551;4558.749268790249;4559.954400455953;4564.520725323926;4572.862814115582;4588.931166032446;4256.59562341577;4407.80164892587;4407.977960110179;4409.22838503267;4425.468943726773;4390.604845563915;4395.340698895191;4398.098389830013;4400.405945197357;4417.315316126005;4297.677674174387;4324.654027677715;4328.415663885461;4334.28484300087;4340.609345158825;4421.668374871833;4438.378626992159;4445.533219011957;4452.398926144929;4454.198244315925;4007.338789099766;4063.329550624114;4398.560143985704;4407.735535867858;4421.049474363027
12:54:57 INFO - PROCESS | 3668 | |5;yui.html;578.6238944685954;611.0437165835465;631.8420394901275;639.4788247578225;700.2857085772512;13203.537876174336;13320;13566.525171618996;13577.91376689865;13610.251436171018;11976;12095.879041209586;12367.196132251398;12375;12386.132970692055;618.403240872558;639.2871947778241;639.7089324357406;641.0608458608139;658.4173006888914;505.64857424090275;519.1667373864949;636.2269842141797;649.581020241946;650.9023646453022;672.8889733194017;681.111149949316;682.4097155960109;682.938535531802;683.1255992329801;677.3497442455249;678.0270312102149;678.7149397253141;679.1510611735331;679.592244653209;389.6590483327089;397.6321902240416;495.69514748429816;497.5198933029608;497.97510124493994;9889.703308900744;10247.077763001365;10265.589376424934;12064.396780161045;12547;12606.117571769979;12727.745445091046;12742.872571274262;12975;13240.86759132406;10540.209484288709;10661.573537058508;10761.932849137958;10867.347959122479;10886.129109671207;3160.972683877726;3200.535922291278;3208.7914285571364;3265.004173727012;3270;65590.67204663946;65638;66223;67109.66445167792;68002;9885.901140988499;10011.199104071657;10053.296269261082;10094.192464602815;10193.286469947032;18499.352522661644;18552.25790968346;18559.44321670352;18560.071996400125;18629.161687724085;6585.275619681831;6658.9001164982565;6676.933230667728;6727.461803055744;6926.896096558556;3506.579210494706;3669.4679271505483;3671.7796932184156;3737.364648009845;3811.6950643948417;2523.470071285014;2663.933401664935;2706.4587082583166;2798.4822807780624;2804.411073674551;455.5717625432083;458.61934594286663;460.2589830373097;466.7409587678871;468.3162582629331;533.6077982682725;541.9756110975015;597.5876645114856;601.8435206846251;607.1590846677384;7603.239676032353;7724.266194711493;7883.132855385903;7900.644470998819;8063;494.6018455143572;526.9841904742864;542.953848922846;558.9077802162678;573.9282589676291;3769.547654281449;3814.879929675999;3911.843526258918;3943.842246310173;3973.443717879499;92571;94084;96693.51653241689;96749;97265.5136724326;81089;84013;84117.15882841215;84676.57661711652;85784;69894.65052674703;76526.23473765302;76561.23438765541;76974.615126924;77743.61128194323;7618.362771094459;7724.536527808349;7977.641006154853;8205.753827385188;8286.129956354496;43673;43677.563224367346;43730.562694372646;44190.779046104566;44852;58362;59267;59428.70285648544;59492.405075948685;60437;9815.509224538602;9852.901470985198;10142.847857282295;10378.117859981987;10636.84044739345;12233.204841685261;12241.877581224073;12425.689357766129;12542.24746515212;12646.30445325516;10641;10658.73353166177;10684.89315106839;10695.358278503314;11094.334339939629;11032.724181895517;11081.39052352096;11280.661580152608;11311.264767790068;11431.314121152758;10330.225233107396;10942.17933654963;11064.446777660924;11150.553977840795;11492.94253528727;9226.40028398152;9448.95275523618;9705.417674939526;9832.410055396698;10025.398476091457;9199.264058875407;9294.767630809283;9463.242940564876;9503.605434046376;9715.27135464857
12:54:57 INFO - PROCESS | 3668 | __end_tp_report
12:54:57 INFO - PROCESS | 3668 | __start_cc_report
12:54:57 INFO - PROCESS | 3668 | _x_x_mozilla_cycle_collect,1005
12:54:57 INFO - PROCESS | 3668 | __end_cc_report
12:54:57 INFO - PROCESS | 3668 | __startTimestamp1474574097892__endTimestamp
12:54:57 INFO - PROCESS | 3668 |
12:54:57 INFO - PROCESS | 3668 | ------- Summary: start -------
12:54:57 INFO - PROCESS | 3668 | Number of tests: 6
12:54:57 INFO - PROCESS | 3668 |
12:54:57 INFO - PROCESS | 3668 | [#0] dojo.html Cycles:165 Average:3820.40 Median:3973.84 stddev:455.64 (11.5%) stddev-sans-first:439.65
12:54:57 INFO - PROCESS | 3668 | Values: 2231.3 2278.6 2301.8 2331.2 2357.4 3921.8 3929.8 3936.4 3989.8 4009.8 3788.3 3793.9 3903.8 3904.6 4028.2 3380.4 3449.7 3460.2 3491.3 3521.3 3497.9 3500.4 3526.3 3548.1 3563.1 3493.8 3495.9 3523.9 3540.5 3547.3 3378.3 3399.5 3425.6 3440.9 3467.6 3343.7 3475.3 3523.2 3532.1 3535.6 3905.8 3913.9 3967.2 3975.0 3975.2 3945.6 4010.6 4063.5 4141.2 4171.5 4094.4 4119.5 4219.2 4228.6 4233.5 3849.7 3868.5 3912.4 3935.7 3944.4 4080.8 4132.1 4191.4 4206.1 4299.7 4048.0 4081.8 4137.8 4139.3 4142.5 4028.2 4182.9 4191.2 4197.6 4201.4 3928.3 3944.4 4086.6 4157.6 4169.5 3929.6 3973.8 4000.3 4134.2 4144.3 2904.6 2992.3 2993.0 2994.5 3003.2 2527.4 2646.8 2680.3 2737.7 2738.3 3781.4 3790.1 3806.4 3809.6 3859.5 4128.4 4165.2 4209.5 4209.9 4215.7 3807.3 3853.0 3860.0 3897.0 3930.9 3412.3 3417.1 3447.2 3526.7 3563.4 4145.2 4260.1 4307.9 4308.5 4313.9 4139.8 4152.6 4153.2 4209.3 4211.2 4108.0 4156.1 4233.1 4300.9 4308.8 4094.7 4117.0 4148.5 4166.7 4171.8 4087.0 4125.7 4185.9 4207.1 4207.2 3866.0 3933.0 3970.8 3998.1 4085.1 3995.4 4010.3 4027.2 4031.3 4046.2 3869.9 3915.5 3987.3 3993.0 3993.3 3874.0 3900.1 4002.7 4071.7 4077.8 3967.9 3998.6 3999.8 4009.9 4038.1
12:54:57 INFO - PROCESS | 3668 |
12:54:57 INFO - PROCESS | 3668 | [#1] ext.html Cycles:180 Average:40441.36 Median:18619.97 stddev:68838.97 (369.7%) stddev-sans-first:68966.92
12:54:57 INFO - PROCESS | 3668 | Values: 547.8 555.6 558.9 565.7 575.5 26855.2 28096.2 28107.0 36515.6 36543.3 37125.6 37847.9 38389.0 38398.0 38422.8 7649.4 7865.0 7870.3 7891.8 7908.3 6426.8 6500.6 6600.1 7692.6 7784.8 6696.7 6767.5 9829.5 10107.0 10117.3 9970.1 10082.4 10106.8 10139.1 10166.9 9883.2 9932.3 9950.0 9965.6 10017.1 25256.2 25766.2 34337.5 34991.3 35034.0 2758.0 2788.2 2795.8 2802.7 2862.7 26971.5 27048.6 27205.0 27496.3 27925.6 3211.1 3276.1 3318.7 3373.1 3437.6 216336.0 219120.0 220087.0 220645.0 220807.0 46914.0 47007.3 47037.3 47410.8 47441.3 44995.6 45839.0 47561.5 47599.3 48689.0 9762.8 9847.4 9926.6 9954.5 10009.9 6768.7 6781.3 6793.2 6804.5 6852.0 12780.3 15263.7 15282.5 15299.6 15406.2 4894.7 5793.5 6078.7 6204.2 6244.8 744.7 770.9 781.6 826.2 868.3 24975.9 25617.2 25791.9 25859.9 25867.7 860.5 884.2 903.3 921.2 952.2 12937.2 13519.7 13647.0 13723.9 13758.4 281044.0 301593.0 309148.0 309848.0 314208.0 44026.1 44753.3 44871.8 44939.0 44939.3 964.7 996.2 1074.4 1079.0 1080.2 23813.8 26215.0 26349.2 26392.6 26630.3 37992.0 38059.2 38227.0 38310.8 39162.8 252028.0 259889.0 260888.0 261382.0 261736.0 20749.9 21323.3 21461.9 22542.3 22587.7 38216.0 38243.4 38948.2 38949.8 39365.2 32525.0 33037.9 33348.2 33437.8 34332.0 32438.4 32745.8 33866.8 34226.7 34354.3 31677.8 32633.2 32916.7 32979.3 33048.8 28736.7 29857.3 30537.7 30901.4 31324.7 22459.0 23646.0 23972.2 24082.9 24196.8
12:54:57 INFO - PROCESS | 3668 |
12:54:57 INFO - PROCESS | 3668 | [#2] jquery.html Cycles:180 Average:20040.89 Median:51313.12 stddev:46531.83 (90.7%) stddev-sans-first:46638.71
12:54:57 INFO - PROCESS | 3668 | Values: 277.4 304.5 307.2 309.6 316.1 11456.8 12346.8 16125.6 16368.8 16698.7 14898.6 15019.8 15514.4 15527.9 15670.9 4858.7 5049.8 5065.3 5067.6 5069.6 3523.6 3634.3 3694.0 4993.6 5072.2 3692.6 3919.6 5625.4 5798.4 5909.4 5867.3 5886.3 5896.4 5900.1 5902.0 4364.1 4675.1 4714.4 4731.9 5089.1 10822.7 10890.9 10926.0 10990.6 10998.0 1623.4 1672.6 1901.9 1964.0 2200.0 8397.5 8437.2 8468.8 8496.7 8580.4 1511.7 1546.9 1570.9 1596.8 1600.9 63029.4 64153.4 64169.4 65991.0 68248.0 8621.4 8627.0 8693.1 8965.1 9006.5 10359.1 10759.2 10972.0 11661.0 11892.6 5727.3 5846.3 5867.1 5919.5 5919.9 5245.3 5328.7 5372.3 5440.5 5455.8 3759.6 3809.1 3830.5 3860.7 3868.1 1546.4 1571.5 1589.5 1589.9 1612.5 769.4 780.3 789.4 817.9 853.7 15987.1 16250.8 16453.3 16623.4 16893.6 773.6 787.4 841.0 857.0 895.6 8644.4 9104.7 9885.7 9900.5 10003.0 270033.0 274924.0 276915.0 277191.0 278747.0 72862.6 73915.0 75173.0 75495.2 76141.0 792.2 810.9 811.6 815.6 817.1 14804.6 15174.8 15192.6 15289.6 15367.4 37430.3 37755.4 37852.4 38699.4 38768.4 49627.8 49922.8 50945.5 51680.7 52120.5 6683.6 6702.8 6932.0 6935.1 6957.2 10024.5 10133.1 10443.6 10757.0 11070.3 8503.5 8651.2 8682.2 8793.2 8936.8 9232.7 9252.6 9278.3 9361.2 9560.2 8023.0 8616.4 8668.6 8717.8 9064.6 6608.6 6661.7 6726.8 6843.7 6866.5 6628.6 8676.0 8842.9 8916.5 8968.5
12:54:57 INFO - PROCESS | 3668 |
12:54:57 INFO - PROCESS | 3668 | [#3] mootools.html Cycles:180 Average:4164.58 Median:5302.47 stddev:2339.81 (44.1%) stddev-sans-first:2334.87
12:54:57 INFO - PROCESS | 3668 | Values: 1077.0 1207.7 1213.6 1260.8 1530.7 5378.1 6551.1 6755.5 6760.1 6917.1 6332.8 6415.2 6737.9 6783.5 6785.9 1793.9 1803.7 1819.4 1823.7 1827.2 1757.0 1806.7 1811.4 1819.0 1819.1 1823.6 1823.7 1828.1 1828.9 1835.8 1707.8 1710.3 1747.0 1748.7 1749.9 1711.1 1719.5 1749.3 1750.2 1752.0 6466.8 6599.7 6682.4 6698.0 6698.3 3696.4 3836.6 3853.1 3854.9 3855.8 2408.3 2411.9 2448.8 2449.8 2450.3 736.4 743.4 778.5 782.3 784.4 8052.0 8102.1 8212.0 8237.2 8264.8 3165.4 3166.4 3234.1 3236.7 3258.4 6602.5 6605.7 6615.8 6629.3 6646.3 3522.3 3639.2 3689.9 3700.4 3714.5 2915.9 3027.5 3364.0 3396.0 3442.3 2776.3 2887.7 2907.4 2907.8 2941.1 860.1 865.5 877.7 878.3 883.3 713.6 721.7 726.1 733.5 737.6 4164.1 4164.3 4198.1 4274.5 4345.5 728.2 757.4 766.2 768.5 781.8 2829.5 2927.8 2932.2 2946.6 3009.6 7644.5 7776.6 7959.9 7969.1 8044.8 7571.2 7981.4 7982.5 7984.3 8000.8 6112.3 6199.1 6253.2 6256.2 6295.7 4224.1 4224.3 4226.3 4291.8 4347.7 7469.2 7505.9 7506.6 7513.5 7524.6 7254.2 7589.1 7631.4 7672.9 7673.8 5328.7 5336.5 5343.1 5453.7 5466.5 5307.8 5347.0 6146.9 6222.0 6256.2 5583.8 5611.2 5712.5 5713.6 5758.6 5297.1 5547.9 5583.4 5595.0 5598.9 4769.7 4989.1 5018.1 5039.2 5047.2 3294.0 3462.6 3485.4 3506.5 3556.3 3493.8 3510.3 3515.2 3530.3 3548.0
12:54:57 INFO - PROCESS | 3668 |
12:54:57 INFO - PROCESS | 3668 | [#4] prototype.html Cycles:180 Average:3722.61 Median:4253.89 stddev:1102.93 (25.9%) stddev-sans-first:1074.43
12:54:57 INFO - PROCESS | 3668 | Values: 230.7 243.8 243.9 246.4 250.7 4251.2 4297.8 4318.4 4320.5 4321.5 4066.2 4112.6 4187.2 4258.3 4266.9 3222.2 3226.9 3238.4 3246.5 3250.2 3234.7 3241.3 3243.2 3247.5 3250.6 2997.7 3094.2 3099.7 3224.3 3226.8 3124.5 3192.7 3218.4 3224.5 3226.4 2854.6 2865.8 2873.8 2875.5 2877.0 4184.4 4185.7 4187.2 4190.2 4199.7 3762.2 3793.7 3797.3 3797.8 3799.3 4290.9 4410.3 4419.1 4433.5 4436.8 3197.2 3199.8 3207.2 3207.5 3211.6 4480.5 4592.7 4597.3 4610.0 4610.4 3287.4 3291.5 3291.7 3296.0 3298.8 3315.4 3316.7 3324.3 3337.4 3338.6 3643.2 3758.2 3771.4 3772.4 3778.8 4385.3 4401.9 4414.3 4425.0 4435.0 1129.3 1135.9 1142.4 1149.6 1151.8 695.1 712.1 741.4 778.8 796.1 4131.9 4141.8 4188.5 4212.3 4213.3 4415.4 4491.3 4529.5 4540.4 4546.4 4129.4 4138.8 4155.4 4207.9 4208.4 2315.9 2361.3 2398.9 2418.2 2423.4 4674.3 4685.2 4686.5 4724.6 4736.6 4719.2 4724.8 4725.1 4741.1 4746.4 4662.1 4718.6 4733.0 4739.3 4742.6 4463.6 4508.6 4600.7 4634.7 4645.3 4436.4 4505.0 4522.2 4523.5 4525.8 4614.9 4622.2 4688.6 4700.0 4717.0 3267.9 3269.4 3283.7 3298.8 3313.3 4558.7 4560.0 4564.5 4572.9 4588.9 4256.6 4407.8 4408.0 4409.2 4425.5 4390.6 4395.3 4398.1 4400.4 4417.3 4297.7 4324.7 4328.4 4334.3 4340.6 4421.7 4438.4 4445.5 4452.4 4454.2 4007.3 4063.3 4398.6 4407.7 4421.0
12:54:57 INFO - PROCESS | 3668 |
12:54:57 INFO - PROCESS | 3668 | [#5] yui.html Cycles:180 Average:17491.32 Median:496.61 stddev:25324.52 (5099.5%) stddev-sans-first:25363.72
12:54:57 INFO - PROCESS | 3668 | Values: 578.6 611.0 631.8 639.5 700.3 13203.5 13320.0 13566.5 13577.9 13610.3 11976.0 12095.9 12367.2 12375.0 12386.1 618.4 639.3 639.7 641.1 658.4 505.6 519.2 636.2 649.6 650.9 672.9 681.1 682.4 682.9 683.1 677.3 678.0 678.7 679.2 679.6 389.7 397.6 495.7 497.5 498.0 9889.7 10247.1 10265.6 12064.4 12547.0 12606.1 12727.7 12742.9 12975.0 13240.9 10540.2 10661.6 10761.9 10867.3 10886.1 3161.0 3200.5 3208.8 3265.0 3270.0 65590.7 65638.0 66223.0 67109.7 68002.0 9885.9 10011.2 10053.3 10094.2 10193.3 18499.4 18552.3 18559.4 18560.1 18629.2 6585.3 6658.9 6676.9 6727.5 6926.9 3506.6 3669.5 3671.8 3737.4 3811.7 2523.5 2663.9 2706.5 2798.5 2804.4 455.6 458.6 460.3 466.7 468.3 533.6 542.0 597.6 601.8 607.2 7603.2 7724.3 7883.1 7900.6 8063.0 494.6 527.0 543.0 558.9 573.9 3769.5 3814.9 3911.8 3943.8 3973.4 92571.0 94084.0 96693.5 96749.0 97265.5 81089.0 84013.0 84117.2 84676.6 85784.0 69894.7 76526.2 76561.2 76974.6 77743.6 7618.4 7724.5 7977.6 8205.8 8286.1 43673.0 43677.6 43730.6 44190.8 44852.0 58362.0 59267.0 59428.7 59492.4 60437.0 9815.5 9852.9 10142.8 10378.1 10636.8 12233.2 12241.9 12425.7 12542.2 12646.3 10641.0 10658.7 10684.9 10695.4 11094.3 11032.7 11081.4 11280.7 11311.3 11431.3 10330.2 10942.2 11064.4 11150.6 11492.9 9226.4 9449.0 9705.4 9832.4 10025.4 9199.3 9294.8 9463.2 9503.6 9715.3
12:54:57 INFO - PROCESS | 3668 | -------- Summary: end --------
12:54:57 INFO - PROCESS | 3668 |
12:54:58 INFO - TEST-INFO | 3668: exit 0
12:54:59 INFO - TEST-OK | dromaeo_css | took 1123507ms
12:54:59 INFO - TEST-START | kraken
12:54:59 INFO - operating with platform_type : w7_
12:54:59 INFO - Initialising browser for kraken test...
12:54:59 INFO - TEST-INFO | started process 2692 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpkm7gin\profile http://localhost:49609/getInfo.html)
12:55:03 INFO - PROCESS | 2692 | __metrics Screen width/height:1600/1200
12:55:03 INFO - PROCESS | 2692 | colorDepth:24
12:55:03 INFO - PROCESS | 2692 | Browser inner width/height: 1010/674
12:55:03 INFO - PROCESS | 2692 | __metrics
12:55:04 INFO - PROCESS | 2692 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows"
12:55:04 INFO - PROCESS | 2692 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
12:55:05 INFO - PROCESS | 2692 | console.error:
12:55:05 INFO - PROCESS | 2692 | Message: Error: Connection closed before committing the transaction.
12:55:05 INFO - PROCESS | 2692 | Stack:
12:55:05 INFO - PROCESS | 2692 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:937:23
12:55:05 INFO - PROCESS | 2692 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7
12:55:05 INFO - PROCESS | 2692 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11
12:55:05 INFO - PROCESS | 2692 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7
12:55:05 INFO - PROCESS | 2692 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7
12:55:05 INFO - PROCESS | 2692 | TaskImpl_run@resource://gre/modules/Task.jsm:324:13
12:55:05 INFO - PROCESS | 2692 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9
12:55:05 INFO - PROCESS | 2692 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15
12:55:05 INFO - PROCESS | 2692 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5
12:55:05 INFO - PROCESS | 2692 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29
12:55:05 INFO - PROCESS | 2692 | Barrier/this.client.addBlocker/promise13.2%
12:56:05 INFO - TinderboxPrint: I/O read bytes / time
144,675,840 / 1,980,260
12:56:05 INFO - TinderboxPrint: I/O write bytes / time
469,950,976 / 3,478,860
12:56:05 INFO - TinderboxPrint: CPU idle
8,305.2 (86.8%)
12:56:05 INFO - TinderboxPrint: CPU user
1,221.4 (12.8%)
12:56:05 INFO - install - Wall time: 3s; CPU: 11.0%; Read bytes: 0; Write bytes: 35452416; Read time: 0; Write time: 334800
12:56:05 INFO - run-tests - Wall time: 1193s; CPU: 13.0%; Read bytes: 144675840; Write bytes: 396653568; Read time: 1980260; Write time: 3012360
12:56:05 INFO - Running post-run listener: _upload_blobber_files
12:56:05 INFO - Blob upload gear active.
12:56:05 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir.
12:56:05 INFO - Files from C:\slave\test\build\blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com
12:56:05 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', 'Autoland-Non-PGO', '-d', 'C:\\slave\\test\\build\\blobber_upload_dir', '--output-manifest', 'C:\\slave\\test\\build\\uploaded_files.json']
12:56:05 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 Autoland-Non-PGO -d C:\slave\test\build\blobber_upload_dir --output-manifest C:\slave\test\build\uploaded_files.json
12:56:06 INFO - (blobuploader) - INFO - Open directory for files ...
12:56:06 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log ...
12:56:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
12:56:06 INFO - (blobuploader) - INFO - Uploading, attempt #1.
12:56:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded
12:56:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
12:56:08 INFO - (blobuploader) - INFO - Done attempting.
12:56:08 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log ...
12:56:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
12:56:08 INFO - (blobuploader) - INFO - Uploading, attempt #1.
12:56:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded
12:56:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
12:56:09 INFO - (blobuploader) - INFO - Done attempting.
12:56:09 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ...
12:56:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
12:56:09 INFO - (blobuploader) - INFO - Uploading, attempt #1.
12:56:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
12:56:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
12:56:16 INFO - (blobuploader) - INFO - Done attempting.
12:56:16 INFO - (blobuploader) - INFO - Iteration through files over.
12:56:16 INFO - Return code: 0
12:56:16 INFO - rmtree: C:\slave\test\build\uploaded_files.json
12:56:16 INFO - Using _rmtree_windows ...
12:56:16 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1
12:56:16 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/ac3f95008c9dfd27314222aadf4d0875d73bb73da8e5c744818843d0ecd35b6ccb0477e6fd9ba10313f8fbfe8e899f69258276514998eb6de65c8cebcae4b559", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/297b4a53e2544ddb874fcdffb02ef149773a8918ee0483f5aefa51755382220bccdde2910c4b4d6d26217bde642e8845d6710f22fd43aa7bda2e435935fee642", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/57d7a4a128e24a710705a99c792641935de344894ce72a82ebf069a2d046941327cf7f5a440971f7cc045280a3ddf86258b745470a18abd4dec2236a9aa49006"}
12:56:16 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files
12:56:16 INFO - Writing to file C:\slave\test\properties\blobber_files
12:56:16 INFO - Contents:
12:56:16 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/ac3f95008c9dfd27314222aadf4d0875d73bb73da8e5c744818843d0ecd35b6ccb0477e6fd9ba10313f8fbfe8e899f69258276514998eb6de65c8cebcae4b559", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/297b4a53e2544ddb874fcdffb02ef149773a8918ee0483f5aefa51755382220bccdde2910c4b4d6d26217bde642e8845d6710f22fd43aa7bda2e435935fee642", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/57d7a4a128e24a710705a99c792641935de344894ce72a82ebf069a2d046941327cf7f5a440971f7cc045280a3ddf86258b745470a18abd4dec2236a9aa49006"}
12:56:16 INFO - Running post-run listener: copy_logs_to_upload_dir
12:56:16 INFO - Copying logs to upload dir...
12:56:16 INFO - mkdir: C:\slave\test\build\upload\logs
12:56:16 INFO - Copying logs to upload dir...
12:56:16 INFO - Using _rmtree_windows ...
12:56:16 INFO - Using _rmtree_windows ...
12:56:16 INFO - Using _rmtree_windows ...
12:56:16 INFO - Using _rmtree_windows ...
12:56:16 INFO - Using _rmtree_windows ...
12:56:16 INFO - Using _rmtree_windows ...
12:56:16 INFO - Using _rmtree_windows ...
program finished with exit code 0
elapsedTime=1440.353000
========= master_lag: 0.83 =========
========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 1 secs) (at 2016-09-22 12:56:17.102472) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-22 12:56:17.103303) =========
'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-123
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-123
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-123
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/Autoland-Non-PGO/sha512/ac3f95008c9dfd27314222aadf4d0875d73bb73da8e5c744818843d0ecd35b6ccb0477e6fd9ba10313f8fbfe8e899f69258276514998eb6de65c8cebcae4b559", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/297b4a53e2544ddb874fcdffb02ef149773a8918ee0483f5aefa51755382220bccdde2910c4b4d6d26217bde642e8845d6710f22fd43aa7bda2e435935fee642", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/57d7a4a128e24a710705a99c792641935de344894ce72a82ebf069a2d046941327cf7f5a440971f7cc045280a3ddf86258b745470a18abd4dec2236a9aa49006"}
build_url:https://queue.taskcluster.net/v1/task/La_s1kTOTIm9u6v6rE6Udw/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
program finished with exit code 0
elapsedTime=0.101000
build_url: 'https://queue.taskcluster.net/v1/task/La_s1kTOTIm9u6v6rE6Udw/artifacts/public/build/firefox-52.0a1.en-US.win32.zip'
blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/ac3f95008c9dfd27314222aadf4d0875d73bb73da8e5c744818843d0ecd35b6ccb0477e6fd9ba10313f8fbfe8e899f69258276514998eb6de65c8cebcae4b559", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/297b4a53e2544ddb874fcdffb02ef149773a8918ee0483f5aefa51755382220bccdde2910c4b4d6d26217bde642e8845d6710f22fd43aa7bda2e435935fee642", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/57d7a4a128e24a710705a99c792641935de344894ce72a82ebf069a2d046941327cf7f5a440971f7cc045280a3ddf86258b745470a18abd4dec2236a9aa49006"}'
========= master_lag: 0.08 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-22 12:56:17.282950) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-22 12:56:17.283281) =========
'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-123
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-123
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-123
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-09-22 12:56:17.395319) =========
========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-09-22 12:56:17.395745) =========
========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-09-22 12:56:17.947972) =========
========= Total master_lag: 1.04 =========