builder: mozilla-central_win7_ix_test-dromaeojs
slave: t-w732-ix-131
starttime: 1480718681.74
results: success (0)
buildid: 20161202130943
builduid: 1f85e82a10ea4eba902f878cb8e17694
revision: bfa85d23df57c8a1db17c99b267667becc1c4afd
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-02 14:44:41.743925) =========
master: http://buildbot-master126.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-02 14:44:41.744864) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-02 14:44:41.745199) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-02 14:44:41.922611) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-02 14:44:41.922945) =========
'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-131
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-131
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-131
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
--14:44:42-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
=> `archiver_client.py'
Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102
Connecting to hg.mozilla.org|63.245.215.25|:443... connected.
WARNING: Certificate verification error for hg.mozilla.org: certificate signature failure
HTTP request sent, awaiting response... 200 Script output follows
Length: 12,179 (12K) [text/x-python]
0K .......... . 100% 18.96 MB/s
14:44:43 (18.96 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=1.233000
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-02 14:44:43.177844) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 15 secs) (at 2016-12-02 14:44:43.178229) =========
'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-131
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-131
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-131
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
program finished with exit code 0
elapsedTime=15.301000
========= master_lag: 0.01 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 15 secs) (at 2016-12-02 14:44:58.493428) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-02 14:44:58.494383) =========
'bash' '-c' u'python archiver_client.py mozharness --repo mozilla-central --rev bfa85d23df57c8a1db17c99b267667becc1c4afd --destination scripts --debug'
in dir C:\slave\test\. (timeout 1200 secs)
watching logfiles {}
argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev bfa85d23df57c8a1db17c99b267667becc1c4afd --destination scripts --debug']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-131
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-131
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-131
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-12-02 14:44:59,125 truncating revision to first 12 chars
2016-12-02 14:44:59,125 Setting DEBUG logging.
2016-12-02 14:44:59,125 attempt 1/10
2016-12-02 14:44:59,125 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/bfa85d23df57?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2016-12-02 14:44:59,678 unpacking tar archive at: mozilla-central-bfa85d23df57/testing/mozharness/
2016-12-02 14:44:59,819 skipping symlink on windows: configs/single_locale/linux32.py
program finished with exit code 0
elapsedTime=1.529000
========= master_lag: 0.11 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-02 14:45:00.133728) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-02 14:45:00.134095) =========
script_repo_revision: bfa85d23df57c8a1db17c99b267667becc1c4afd
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-02 14:45:00.134588) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-02 14:45:00.134917) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-02 14:45:00.145031) =========
========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 33 secs) (at 2016-12-02 14:45:00.145388) =========
'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Firefox-Non-PGO' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Firefox-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', 'Firefox-Non-PGO', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-131
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-131
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-131
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
14:45:00 INFO - MultiFileLogger online at 20161202 14:45:00 in C:\slave\test
14:45:00 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO
14:45:00 INFO - Dumping config to C:\slave\test\logs\localconfig.json.
14:45:00 INFO - {'append_to_log': False,
14:45:00 INFO - 'base_work_dir': 'C:\\slave\\test',
14:45:00 INFO - 'blob_upload_branch': 'Firefox-Non-PGO',
14:45:00 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt',
14:45:00 INFO - 'branch': 'Firefox-Non-PGO',
14:45:00 INFO - 'buildbot_json_path': 'buildprops.json',
14:45:00 INFO - 'config_files': ('talos/windows_config.py',),
14:45:00 INFO - 'default_actions': ('clobber',
14:45:00 INFO - 'read-buildbot-config',
14:45:00 INFO - 'download-and-extract',
14:45:00 INFO - 'populate-webroot',
14:45:00 INFO - 'create-virtualenv',
14:45:00 INFO - 'install',
14:45:00 INFO - 'run-tests'),
14:45:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
14:45:00 INFO - 'download_minidump_stackwalk': True,
14:45:00 INFO - 'download_symbols': 'ondemand',
14:45:00 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python',
14:45:00 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'),
14:45:00 INFO - 'hg': 'c:/mozilla-build/hg/hg',
14:45:00 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python',
14:45:00 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'),
14:45:00 INFO - 'python': 'c:/mozilla-build/python27/python.exe',
14:45:00 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe',
14:45:00 INFO - 'C:/mozilla-build/tooltool.py'),
14:45:00 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe',
14:45:00 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')},
14:45:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
14:45:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
14:45:00 INFO - 'installer_path': 'installer.exe',
14:45:00 INFO - 'log_level': 'info',
14:45:00 INFO - 'log_name': 'talos',
14:45:00 INFO - 'log_to_console': True,
14:45:00 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe',
14:45:00 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe',
14:45:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest',
14:45:00 INFO - 'opt_config_files': (),
14:45:00 INFO - 'pip_index': False,
14:45:00 INFO - 'sps_profile': False,
14:45:00 INFO - 'sps_profile_interval': 0,
14:45:00 INFO - 'suite': 'dromaeojs',
14:45:00 INFO - 'system_bits': '32',
14:45:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
14:45:00 INFO - 'title': 'T-W732-IX-131',
14:45:00 INFO - 'use_talos_json': True,
14:45:00 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'),
14:45:00 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv',
14:45:00 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll',
14:45:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
14:45:00 INFO - 'work_dir': 'build'}
14:45:00 INFO - [mozharness: 2016-12-02 22:45:00.892000Z] Running clobber step.
14:45:00 INFO - Running pre-action listener: _resource_record_pre_action
14:45:00 INFO - Running main action method: clobber
14:45:00 INFO - rmtree: C:\slave\test\build
14:45:00 INFO - Using _rmtree_windows ...
14:45:00 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1
14:46:44 INFO - Running post-action listener: _resource_record_post_action
14:46:44 INFO - [mozharness: 2016-12-02 22:46:44.203000Z] Finished clobber step (success)
14:46:44 INFO - [mozharness: 2016-12-02 22:46:44.203000Z] Running read-buildbot-config step.
14:46:44 INFO - Running pre-action listener: _resource_record_pre_action
14:46:44 INFO - Running main action method: read_buildbot_config
14:46:44 INFO - Using buildbot properties:
14:46:44 INFO - {
14:46:44 INFO - "project": "",
14:46:44 INFO - "product": "firefox",
14:46:44 INFO - "script_repo_revision": "production",
14:46:44 INFO - "builddir": "mozilla-central_win7_ix_test-dromaeojs",
14:46:44 INFO - "repository": "",
14:46:44 INFO - "buildername": "Windows 7 32-bit mozilla-central talos dromaeojs",
14:46:44 INFO - "buildid": "20161202130943",
14:46:44 INFO - "pgo_build": "False",
14:46:44 INFO - "basedir": "C:\\slave\\test",
14:46:44 INFO - "buildnumber": 100,
14:46:44 INFO - "slavename": "t-w732-ix-131",
14:46:44 INFO - "master": "http://buildbot-master126.bb.releng.scl3.mozilla.com:8201/",
14:46:44 INFO - "platform": "win7_ix",
14:46:44 INFO - "scheduler": "tests-mozilla-central-win32-talos",
14:46:44 INFO - "branch": "mozilla-central",
14:46:44 INFO - "repo_path": "mozilla-central",
14:46:44 INFO - "revision": "bfa85d23df57c8a1db17c99b267667becc1c4afd",
14:46:44 INFO - "stage_platform": "win32",
14:46:44 INFO - "builduid": "1f85e82a10ea4eba902f878cb8e17694",
14:46:44 INFO - "slavebuilddir": "test"
14:46:44 INFO - }
14:46:44 INFO - Found installer url https://queue.taskcluster.net/v1/task/KQoUN0VTTkyg2Ia4JKY-oQ/artifacts/public/build/firefox-53.0a1.en-US.win32.zip.
14:46:44 INFO - Running post-action listener: _resource_record_post_action
14:46:44 INFO - [mozharness: 2016-12-02 22:46:44.207000Z] Finished read-buildbot-config step (success)
14:46:44 INFO - [mozharness: 2016-12-02 22:46:44.207000Z] Running download-and-extract step.
14:46:44 INFO - Running pre-action listener: _resource_record_pre_action
14:46:44 INFO - Running main action method: download_and_extract
14:46:44 INFO - mkdir: C:\slave\test\build\tests
14:46: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')]}
14:46:44 INFO - trying https://queue.taskcluster.net/v1/task/KQoUN0VTTkyg2Ia4JKY-oQ/artifacts/public/build/firefox-53.0a1.en-US.win32.test_packages.json
14:46:44 INFO - Downloading https://queue.taskcluster.net/v1/task/KQoUN0VTTkyg2Ia4JKY-oQ/artifacts/public/build/firefox-53.0a1.en-US.win32.test_packages.json to C:\slave\test\build\firefox-53.0a1.en-US.win32.test_packages.json
14:46:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KQoUN0VTTkyg2Ia4JKY-oQ/artifacts/public/build/firefox-53.0a1.en-US.win32.test_packages.json', 'file_name': 'C:\\slave\\test\\build\\firefox-53.0a1.en-US.win32.test_packages.json'}, attempt #1
14:46:45 INFO - Downloaded 1274 bytes.
14:46:45 INFO - Reading from file C:\slave\test\build\firefox-53.0a1.en-US.win32.test_packages.json
14:46:45 INFO - Using the following test package requirements:
14:46:45 INFO - {u'common': [u'firefox-53.0a1.en-US.win32.common.tests.zip'],
14:46:45 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.win32.common.tests.zip',
14:46:45 INFO - u'firefox-53.0a1.en-US.win32.cppunittest.tests.zip'],
14:46:45 INFO - u'gtest': [u'firefox-53.0a1.en-US.win32.common.tests.zip',
14:46:45 INFO - u'firefox-53.0a1.en-US.win32.gtest.tests.zip'],
14:46:45 INFO - u'jittest': [u'firefox-53.0a1.en-US.win32.common.tests.zip',
14:46:45 INFO - u'jsshell-win32.zip'],
14:46:45 INFO - u'mochitest': [u'firefox-53.0a1.en-US.win32.common.tests.zip',
14:46:45 INFO - u'firefox-53.0a1.en-US.win32.mochitest.tests.zip'],
14:46:45 INFO - u'mozbase': [u'firefox-53.0a1.en-US.win32.common.tests.zip'],
14:46:45 INFO - u'reftest': [u'firefox-53.0a1.en-US.win32.common.tests.zip',
14:46:45 INFO - u'firefox-53.0a1.en-US.win32.reftest.tests.zip'],
14:46:45 INFO - u'talos': [u'firefox-53.0a1.en-US.win32.common.tests.zip',
14:46:45 INFO - u'firefox-53.0a1.en-US.win32.talos.tests.zip'],
14:46:45 INFO - u'web-platform': [u'firefox-53.0a1.en-US.win32.common.tests.zip',
14:46:45 INFO - u'firefox-53.0a1.en-US.win32.web-platform.tests.zip'],
14:46:45 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.win32.common.tests.zip',
14:46:45 INFO - u'firefox-53.0a1.en-US.win32.xpcshell.tests.zip']}
14:46:45 INFO - Downloading packages: [u'firefox-53.0a1.en-US.win32.common.tests.zip'] for test suite category: common
14:46:45 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/KQoUN0VTTkyg2Ia4JKY-oQ/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip
14:46:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KQoUN0VTTkyg2Ia4JKY-oQ/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip'}, attempt #1
14:46:45 INFO - Fetch https://queue.taskcluster.net/v1/task/KQoUN0VTTkyg2Ia4JKY-oQ/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip into memory
14:46:46 INFO - Expected file size: 16889602
14:46:46 INFO - Obtained file size: 16889602
14:47:16 INFO - Downloading packages: [u'firefox-53.0a1.en-US.win32.common.tests.zip', u'firefox-53.0a1.en-US.win32.talos.tests.zip'] for test suite category: talos
14:47:16 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/KQoUN0VTTkyg2Ia4JKY-oQ/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip
14:47:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KQoUN0VTTkyg2Ia4JKY-oQ/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip'}, attempt #1
14:47:16 INFO - Fetch https://queue.taskcluster.net/v1/task/KQoUN0VTTkyg2Ia4JKY-oQ/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip into memory
14:47:17 INFO - Expected file size: 16889602
14:47:17 INFO - Obtained file size: 16889602
14:47:28 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/KQoUN0VTTkyg2Ia4JKY-oQ/artifacts/public/build/firefox-53.0a1.en-US.win32.talos.tests.zip
14:47:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KQoUN0VTTkyg2Ia4JKY-oQ/artifacts/public/build/firefox-53.0a1.en-US.win32.talos.tests.zip'}, attempt #1
14:47:28 INFO - Fetch https://queue.taskcluster.net/v1/task/KQoUN0VTTkyg2Ia4JKY-oQ/artifacts/public/build/firefox-53.0a1.en-US.win32.talos.tests.zip into memory
14:47:29 INFO - Expected file size: 14409734
14:47:29 INFO - Obtained file size: 14409734
14:47: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')]}
14:47:30 INFO - trying https://queue.taskcluster.net/v1/task/KQoUN0VTTkyg2Ia4JKY-oQ/artifacts/public/build/firefox-53.0a1.en-US.win32.zip
14:47:30 INFO - Downloading https://queue.taskcluster.net/v1/task/KQoUN0VTTkyg2Ia4JKY-oQ/artifacts/public/build/firefox-53.0a1.en-US.win32.zip to C:\slave\test\build\firefox-53.0a1.en-US.win32.zip
14:47:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KQoUN0VTTkyg2Ia4JKY-oQ/artifacts/public/build/firefox-53.0a1.en-US.win32.zip', 'file_name': 'C:\\slave\\test\\build\\firefox-53.0a1.en-US.win32.zip'}, attempt #1
14:47:32 INFO - Downloaded 53384084 bytes.
14:47:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KQoUN0VTTkyg2Ia4JKY-oQ/artifacts/public/build/firefox-53.0a1.en-US.win32.zip
14:47:32 INFO - mkdir: C:\slave\test\properties
14:47:32 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url
14:47:32 INFO - Writing to file C:\slave\test\properties\build_url
14:47:32 INFO - Contents:
14:47:32 INFO - build_url:https://queue.taskcluster.net/v1/task/KQoUN0VTTkyg2Ia4JKY-oQ/artifacts/public/build/firefox-53.0a1.en-US.win32.zip
14:47:32 INFO - Running post-action listener: _resource_record_post_action
14:47:32 INFO - Running post-action listener: set_extra_try_arguments
14:47:32 INFO - [mozharness: 2016-12-02 22:47:32.722000Z] Finished download-and-extract step (success)
14:47:32 INFO - [mozharness: 2016-12-02 22:47:32.722000Z] Running populate-webroot step.
14:47:32 INFO - Running pre-action listener: _resource_record_pre_action
14:47:32 INFO - Running main action method: populate_webroot
14:47:32 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']},
14:47:32 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']},
14:47:32 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome',
14:47:32 INFO - u'--tppagecycles',
14:47:32 INFO - u'7'],
14:47:32 INFO - u'tests': [u'tsvgm']}},
14:47:32 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'],
14:47:32 INFO - u'tests': [u'tresize', u'tcanvasmark']},
14:47:32 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']},
14:47:32 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'],
14:47:32 INFO - u'tests': [u'dromaeo_css', u'kraken']},
14:47:32 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']},
14:47:32 INFO - u'g1': {u'pagesets_name': u'tp5n.zip',
14:47:32 INFO - u'talos_options': [u'--disable-e10s'],
14:47:32 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
14:47:32 INFO - u'g1-e10s': {u'pagesets_name': u'tp5n.zip',
14:47:32 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
14:47:32 INFO - u'g2': {u'pagesets_name': u'tp5n.zip',
14:47:32 INFO - u'talos_options': [u'--disable-e10s'],
14:47:32 INFO - u'tests': [u'damp', u'tps']},
14:47:32 INFO - u'g2-e10s': {u'pagesets_name': u'tp5n.zip',
14:47:32 INFO - u'tests': [u'damp', u'tps']},
14:47:32 INFO - u'g3': {u'talos_options': [u'--disable-e10s'],
14:47:32 INFO - u'tests': [u'dromaeo_dom']},
14:47:32 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']},
14:47:32 INFO - u'g4': {u'talos_options': [u'--disable-e10s'],
14:47:32 INFO - u'tests': [u'basic_compositor_video']},
14:47:32 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']},
14:47:32 INFO - u'other': {u'talos_options': [u'--disable-e10s'],
14:47:32 INFO - u'tests': [u'a11yr',
14:47:32 INFO - u'ts_paint',
14:47:32 INFO - u'tpaint',
14:47:32 INFO - u'sessionrestore',
14:47:32 INFO - u'sessionrestore_no_auto_restore',
14:47:32 INFO - u'tabpaint']},
14:47:32 INFO - u'other-e10s': {u'tests': [u'a11yr',
14:47:32 INFO - u'ts_paint',
14:47:32 INFO - u'tpaint',
14:47:32 INFO - u'sessionrestore',
14:47:32 INFO - u'sessionrestore_no_auto_restore',
14:47:32 INFO - u'tabpaint']},
14:47:32 INFO - u'other-e10s_l64': {u'tests': [u'a11yr',
14:47:32 INFO - u'ts_paint',
14:47:32 INFO - u'tpaint',
14:47:32 INFO - u'sessionrestore',
14:47:32 INFO - u'sessionrestore_no_auto_restore',
14:47:32 INFO - u'tabpaint']},
14:47:32 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr',
14:47:32 INFO - u'ts_paint',
14:47:32 INFO - u'tpaint',
14:47:32 INFO - u'sessionrestore',
14:47:32 INFO - u'sessionrestore_no_auto_restore',
14:47:32 INFO - u'tabpaint']},
14:47:32 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'],
14:47:32 INFO - u'tests': [u'a11yr',
14:47:32 INFO - u'ts_paint',
14:47:32 INFO - u'tpaint',
14:47:32 INFO - u'sessionrestore',
14:47:32 INFO - u'sessionrestore_no_auto_restore',
14:47:32 INFO - u'tabpaint']},
14:47:32 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'],
14:47:32 INFO - u'tests': [u'a11yr',
14:47:32 INFO - u'ts_paint',
14:47:32 INFO - u'tpaint',
14:47:32 INFO - u'sessionrestore',
14:47:32 INFO - u'sessionrestore_no_auto_restore',
14:47:32 INFO - u'tabpaint']},
14:47:32 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'],
14:47:32 INFO - u'tests': [u'tsvgx',
14:47:32 INFO - u'tsvgr_opacity',
14:47:32 INFO - u'tart',
14:47:32 INFO - u'tscrollx',
14:47:32 INFO - u'cart',
14:47:32 INFO - u'tsvg_static']},
14:47:32 INFO - u'svgr-e10s': {u'tests': [u'tsvgx',
14:47:32 INFO - u'tsvgr_opacity',
14:47:32 INFO - u'tart',
14:47:32 INFO - u'tscrollx',
14:47:32 INFO - u'cart',
14:47:32 INFO - u'tsvg_static']},
14:47:32 INFO - u'tp5o': {u'pagesets_name': u'tp5n.zip',
14:47:32 INFO - u'talos_options': [u'--disable-e10s'],
14:47:32 INFO - u'tests': [u'tp5o']},
14:47:32 INFO - u'tp5o-e10s': {u'pagesets_name': u'tp5n.zip',
14:47:32 INFO - u'tests': [u'tp5o']},
14:47:32 INFO - u'xperf': {u'pagesets_name': u'tp5n.zip',
14:47:32 INFO - u'talos_options': [u'--disable-e10s',
14:47:32 INFO - u'--xperf_path',
14:47:32 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
14:47:32 INFO - u'tests': [u'tp5n']},
14:47:32 INFO - u'xperf-e10s': {u'pagesets_name': u'tp5n.zip',
14:47:32 INFO - u'talos_options': [u'--xperf_path',
14:47:32 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
14:47:32 INFO - u'tests': [u'tp5n']}},
14:47:32 INFO - u'talos.zip': {u'path': u'',
14:47:32 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}}
14:47:32 INFO - Running post-action listener: _resource_record_post_action
14:47:32 INFO - [mozharness: 2016-12-02 22:47:32.761000Z] Finished populate-webroot step (success)
14:47:32 INFO - [mozharness: 2016-12-02 22:47:32.761000Z] Running create-virtualenv step.
14:47:32 INFO - Running pre-action listener: _resource_record_pre_action
14:47:32 INFO - Running main action method: create_virtualenv
14:47:32 INFO - Creating virtualenv C:\slave\test\build/venv
14:47:32 INFO - mkdir: C:\slave\test\build\venv\Scripts
14:47:32 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll
14:47:32 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'!
14:47:32 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
14:47:32 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv
14:47:36 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe
14:47:42 INFO - Installing distribute......................................................................................................................................................................................done.
14:47:44 INFO - Return code: 0
14:47:44 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version']
14:47:44 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version
14:47:44 INFO - Reading from file tmpfile_stdout
14:47:44 INFO - Output received:
14:47:44 INFO - pip 0.8.2 from c:\slave\test\build\venv\lib\site-packages\pip-0.8.2-py2.7.egg (python 2.7)
14:47:44 INFO - Using _rmtree_windows ...
14:47:44 INFO - Using _rmtree_windows ...
14:47:44 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv
14:47: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')]}
14:47:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
14:47: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')]}
14:47:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
14:47: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018F0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01995F20>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0189B0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019A4E90>, '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 0x012EA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F015C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-131', 'USERDOMAIN': 'T-W732-IX-131', '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-131', '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
14:47: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', 'pip>=1.5'] in C:\slave\test\build
14:47: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 pip>=1.5
14:47:44 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
14:47:44 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
14:47:44 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
14:47:44 INFO - 'COMPUTERNAME': 'T-W732-IX-131',
14:47:44 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
14:47:44 INFO - 'CYGWINBASE': 'C:\\cygwin',
14:47:44 INFO - 'DCLOCATION': 'SCL3',
14:47:44 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
14:47:44 INFO - 'FP_NO_HOST_CHECK': 'NO',
14:47:44 INFO - 'HOMEDRIVE': 'C:',
14:47:44 INFO - 'HOMEPATH': '\\Users\\cltbld',
14:47:44 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
14:47:44 INFO - 'KTS_VERSION': '1.19c',
14:47:44 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
14:47:44 INFO - 'LOGONSERVER': '\\\\T-W732-IX-131',
14:47:44 INFO - 'MONDIR': 'C:\\Monitor_config\\',
14:47:44 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
14:47:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
14:47:44 INFO - 'MOZ_NO_REMOTE': '1',
14:47:44 INFO - 'NO_EM_RESTART': '1',
14:47:44 INFO - 'NUMBER_OF_PROCESSORS': '8',
14:47:44 INFO - 'OS': 'Windows_NT',
14:47:44 INFO - 'OURDRIVE': 'C:',
14:47: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;',
14:47:44 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
14:47:44 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
14:47:44 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
14:47:44 INFO - 'PROCESSOR_LEVEL': '6',
14:47:44 INFO - 'PROCESSOR_REVISION': '1e05',
14:47:44 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
14:47:44 INFO - 'PROGRAMFILES': 'C:\\Program Files',
14:47:44 INFO - 'PROMPT': '$P$G',
14:47:44 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
14:47:44 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
14:47:44 INFO - 'PUBLIC': 'C:\\Users\\Public',
14:47:44 INFO - 'PWD': 'C:\\slave\\test',
14:47:44 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
14:47:44 INFO - 'SLAVEDIR': 'C:\\slave\\',
14:47:44 INFO - 'SYSTEMDRIVE': 'C:',
14:47:44 INFO - 'SYSTEMROOT': 'C:\\windows',
14:47:44 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
14:47:44 INFO - 'TEST1': 'testie',
14:47:44 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
14:47:44 INFO - 'USERDOMAIN': 'T-W732-IX-131',
14:47:44 INFO - 'USERNAME': 'cltbld',
14:47:44 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
14:47:44 INFO - 'WINDIR': 'C:\\windows',
14:47:44 INFO - 'WINDOWS_TRACING_FLAGS': '3',
14:47:44 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
14:47:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
14:47:45 INFO - Ignoring indexes: http://pypi.python.org/simple/
14:47:45 INFO - Downloading/unpacking pip>=1.5
14:47:50 INFO - Running setup.py egg_info for package pip
14:47:50 INFO - warning: no files found matching 'pip\cacert.pem'
14:47:50 INFO - warning: no files found matching '*.html' under directory 'docs'
14:47:50 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
14:47:50 INFO - no previously-included directories found matching 'docs\_build\_sources'
14:47:50 INFO - Installing collected packages: pip
14:47:50 INFO - Found existing installation: pip 0.8.2
14:47:50 INFO - Uninstalling pip:
14:47:50 INFO - Successfully uninstalled pip
14:47:50 INFO - Running setup.py install for pip
14:47:50 INFO - warning: no files found matching 'pip\cacert.pem'
14:47:50 INFO - warning: no files found matching '*.html' under directory 'docs'
14:47:50 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
14:47:50 INFO - no previously-included directories found matching 'docs\_build\_sources'
14:47:50 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts
14:47:50 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts
14:47:50 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts
14:47:50 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts
14:47:50 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts
14:47:50 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts
14:47:50 INFO - Successfully installed pip
14:47:50 INFO - Cleaning up...
14:47:50 INFO - Return code: 0
14:47:50 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv
14:47:50 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')]}
14:47:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
14:47:50 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')]}
14:47:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
14:47:50 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 0x018F0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01995F20>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0189B0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019A4E90>, '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 0x012EA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F015C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-131', 'USERDOMAIN': 'T-W732-IX-131', '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-131', '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
14:47:50 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
14:47:50 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
14:47:50 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
14:47:50 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
14:47:50 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
14:47:50 INFO - 'COMPUTERNAME': 'T-W732-IX-131',
14:47:50 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
14:47:50 INFO - 'CYGWINBASE': 'C:\\cygwin',
14:47:50 INFO - 'DCLOCATION': 'SCL3',
14:47:50 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
14:47:50 INFO - 'FP_NO_HOST_CHECK': 'NO',
14:47:50 INFO - 'HOMEDRIVE': 'C:',
14:47:50 INFO - 'HOMEPATH': '\\Users\\cltbld',
14:47:50 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
14:47:50 INFO - 'KTS_VERSION': '1.19c',
14:47:50 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
14:47:50 INFO - 'LOGONSERVER': '\\\\T-W732-IX-131',
14:47:50 INFO - 'MONDIR': 'C:\\Monitor_config\\',
14:47:50 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
14:47:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
14:47:50 INFO - 'MOZ_NO_REMOTE': '1',
14:47:50 INFO - 'NO_EM_RESTART': '1',
14:47:50 INFO - 'NUMBER_OF_PROCESSORS': '8',
14:47:50 INFO - 'OS': 'Windows_NT',
14:47:50 INFO - 'OURDRIVE': 'C:',
14:47:50 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;',
14:47:50 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
14:47:50 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
14:47:50 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
14:47:50 INFO - 'PROCESSOR_LEVEL': '6',
14:47:50 INFO - 'PROCESSOR_REVISION': '1e05',
14:47:50 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
14:47:50 INFO - 'PROGRAMFILES': 'C:\\Program Files',
14:47:50 INFO - 'PROMPT': '$P$G',
14:47:50 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
14:47:50 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
14:47:50 INFO - 'PUBLIC': 'C:\\Users\\Public',
14:47:50 INFO - 'PWD': 'C:\\slave\\test',
14:47:50 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
14:47:50 INFO - 'SLAVEDIR': 'C:\\slave\\',
14:47:50 INFO - 'SYSTEMDRIVE': 'C:',
14:47:50 INFO - 'SYSTEMROOT': 'C:\\windows',
14:47:50 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
14:47:50 INFO - 'TEST1': 'testie',
14:47:50 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
14:47:50 INFO - 'USERDOMAIN': 'T-W732-IX-131',
14:47:50 INFO - 'USERNAME': 'cltbld',
14:47:50 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
14:47:50 INFO - 'WINDIR': 'C:\\windows',
14:47:50 INFO - 'WINDOWS_TRACING_FLAGS': '3',
14:47:50 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
14:47:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
14:47:51 INFO - Ignoring indexes: https://pypi.python.org/simple/
14:47:51 INFO - Downloading/unpacking psutil>=3.1.1
14:47:51 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
14:47:51 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
14:47:52 INFO - Installing collected packages: psutil
14:47:52 INFO - Successfully installed psutil
14:47:52 INFO - Cleaning up...
14:47:52 INFO - Return code: 0
14:47:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv
14:47:52 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')]}
14:47:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
14:47:52 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')]}
14:47:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
14:47:52 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 0x018F0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01995F20>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0189B0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019A4E90>, '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 0x012EA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F015C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-131', 'USERDOMAIN': 'T-W732-IX-131', '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-131', '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
14:47:52 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
14:47:52 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
14:47:52 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
14:47:52 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
14:47:52 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
14:47:52 INFO - 'COMPUTERNAME': 'T-W732-IX-131',
14:47:52 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
14:47:52 INFO - 'CYGWINBASE': 'C:\\cygwin',
14:47:52 INFO - 'DCLOCATION': 'SCL3',
14:47:52 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
14:47:52 INFO - 'FP_NO_HOST_CHECK': 'NO',
14:47:52 INFO - 'HOMEDRIVE': 'C:',
14:47:52 INFO - 'HOMEPATH': '\\Users\\cltbld',
14:47:52 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
14:47:52 INFO - 'KTS_VERSION': '1.19c',
14:47:52 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
14:47:52 INFO - 'LOGONSERVER': '\\\\T-W732-IX-131',
14:47:52 INFO - 'MONDIR': 'C:\\Monitor_config\\',
14:47:52 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
14:47:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
14:47:52 INFO - 'MOZ_NO_REMOTE': '1',
14:47:52 INFO - 'NO_EM_RESTART': '1',
14:47:52 INFO - 'NUMBER_OF_PROCESSORS': '8',
14:47:52 INFO - 'OS': 'Windows_NT',
14:47:52 INFO - 'OURDRIVE': 'C:',
14:47:52 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;',
14:47:52 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
14:47:52 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
14:47:52 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
14:47:52 INFO - 'PROCESSOR_LEVEL': '6',
14:47:52 INFO - 'PROCESSOR_REVISION': '1e05',
14:47:52 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
14:47:52 INFO - 'PROGRAMFILES': 'C:\\Program Files',
14:47:52 INFO - 'PROMPT': '$P$G',
14:47:52 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
14:47:52 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
14:47:52 INFO - 'PUBLIC': 'C:\\Users\\Public',
14:47:52 INFO - 'PWD': 'C:\\slave\\test',
14:47:52 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
14:47:52 INFO - 'SLAVEDIR': 'C:\\slave\\',
14:47:52 INFO - 'SYSTEMDRIVE': 'C:',
14:47:52 INFO - 'SYSTEMROOT': 'C:\\windows',
14:47:52 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
14:47:52 INFO - 'TEST1': 'testie',
14:47:52 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
14:47:52 INFO - 'USERDOMAIN': 'T-W732-IX-131',
14:47:52 INFO - 'USERNAME': 'cltbld',
14:47:52 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
14:47:52 INFO - 'WINDIR': 'C:\\windows',
14:47:52 INFO - 'WINDOWS_TRACING_FLAGS': '3',
14:47:52 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
14:47:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
14:47:53 INFO - Ignoring indexes: https://pypi.python.org/simple/
14:47:53 INFO - Downloading/unpacking mozsystemmonitor==0.3
14:47:53 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
14:47:53 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
14:47:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz
14:47:54 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor
14:47:54 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)
14:47:54 INFO - Installing collected packages: mozsystemmonitor
14:47:54 INFO - Running setup.py install for mozsystemmonitor
14:47:54 INFO - Successfully installed mozsystemmonitor
14:47:54 INFO - Cleaning up...
14:47:54 INFO - Return code: 0
14:47:54 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv
14:47:54 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')]}
14:47:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
14:47:54 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')]}
14:47:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
14:47:54 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 0x018F0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01995F20>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0189B0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019A4E90>, '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 0x012EA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F015C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-131', 'USERDOMAIN': 'T-W732-IX-131', '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-131', '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
14:47:54 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
14:47:54 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
14:47:54 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
14:47:54 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
14:47:54 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
14:47:54 INFO - 'COMPUTERNAME': 'T-W732-IX-131',
14:47:54 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
14:47:54 INFO - 'CYGWINBASE': 'C:\\cygwin',
14:47:54 INFO - 'DCLOCATION': 'SCL3',
14:47:54 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
14:47:54 INFO - 'FP_NO_HOST_CHECK': 'NO',
14:47:54 INFO - 'HOMEDRIVE': 'C:',
14:47:54 INFO - 'HOMEPATH': '\\Users\\cltbld',
14:47:54 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
14:47:54 INFO - 'KTS_VERSION': '1.19c',
14:47:54 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
14:47:54 INFO - 'LOGONSERVER': '\\\\T-W732-IX-131',
14:47:54 INFO - 'MONDIR': 'C:\\Monitor_config\\',
14:47:54 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
14:47:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
14:47:54 INFO - 'MOZ_NO_REMOTE': '1',
14:47:54 INFO - 'NO_EM_RESTART': '1',
14:47:54 INFO - 'NUMBER_OF_PROCESSORS': '8',
14:47:54 INFO - 'OS': 'Windows_NT',
14:47:54 INFO - 'OURDRIVE': 'C:',
14:47:54 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;',
14:47:54 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
14:47:54 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
14:47:54 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
14:47:54 INFO - 'PROCESSOR_LEVEL': '6',
14:47:54 INFO - 'PROCESSOR_REVISION': '1e05',
14:47:54 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
14:47:54 INFO - 'PROGRAMFILES': 'C:\\Program Files',
14:47:54 INFO - 'PROMPT': '$P$G',
14:47:54 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
14:47:54 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
14:47:54 INFO - 'PUBLIC': 'C:\\Users\\Public',
14:47:54 INFO - 'PWD': 'C:\\slave\\test',
14:47:54 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
14:47:54 INFO - 'SLAVEDIR': 'C:\\slave\\',
14:47:54 INFO - 'SYSTEMDRIVE': 'C:',
14:47:54 INFO - 'SYSTEMROOT': 'C:\\windows',
14:47:54 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
14:47:54 INFO - 'TEST1': 'testie',
14:47:54 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
14:47:54 INFO - 'USERDOMAIN': 'T-W732-IX-131',
14:47:54 INFO - 'USERNAME': 'cltbld',
14:47:54 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
14:47:54 INFO - 'WINDIR': 'C:\\windows',
14:47:54 INFO - 'WINDOWS_TRACING_FLAGS': '3',
14:47:54 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
14:47:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
14:47:54 INFO - Ignoring indexes: https://pypi.python.org/simple/
14:47:54 INFO - Downloading/unpacking jsonschema==2.5.1
14:47:54 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
14:47:54 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
14:47:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl
14:47:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1)
14:47:56 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
14:47:56 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
14:47:56 INFO - Downloading functools32-3.2.3-2.tar.gz
14:47:56 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32
14:47:56 INFO - warning: no files found matching '*.txt'
14:47:56 INFO - no previously-included directories found matching 'build'
14:47:56 INFO - no previously-included directories found matching 'dist'
14:47:56 INFO - no previously-included directories found matching '.git*'
14:47:56 INFO - Installing collected packages: jsonschema, functools32
14:47:56 INFO - Running setup.py install for functools32
14:47:56 INFO - warning: no files found matching '*.txt'
14:47:56 INFO - no previously-included directories found matching 'build'
14:47:56 INFO - no previously-included directories found matching 'dist'
14:47:56 INFO - no previously-included directories found matching '.git*'
14:47:56 INFO - Successfully installed jsonschema functools32
14:47:56 INFO - Cleaning up...
14:47:57 INFO - Return code: 0
14:47:57 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv
14:47:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
14:47:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
14:47:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
14:47:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
14:47:57 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 0x018F0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01995F20>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0189B0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019A4E90>, '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 0x012EA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F015C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-131', 'USERDOMAIN': 'T-W732-IX-131', '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-131', '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
14:47:57 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
14:47:57 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
14:47:57 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
14:47:57 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
14:47:57 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
14:47:57 INFO - 'COMPUTERNAME': 'T-W732-IX-131',
14:47:57 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
14:47:57 INFO - 'CYGWINBASE': 'C:\\cygwin',
14:47:57 INFO - 'DCLOCATION': 'SCL3',
14:47:57 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
14:47:57 INFO - 'FP_NO_HOST_CHECK': 'NO',
14:47:57 INFO - 'HOMEDRIVE': 'C:',
14:47:57 INFO - 'HOMEPATH': '\\Users\\cltbld',
14:47:57 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
14:47:57 INFO - 'KTS_VERSION': '1.19c',
14:47:57 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
14:47:57 INFO - 'LOGONSERVER': '\\\\T-W732-IX-131',
14:47:57 INFO - 'MONDIR': 'C:\\Monitor_config\\',
14:47:57 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
14:47:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
14:47:57 INFO - 'MOZ_NO_REMOTE': '1',
14:47:57 INFO - 'NO_EM_RESTART': '1',
14:47:57 INFO - 'NUMBER_OF_PROCESSORS': '8',
14:47:57 INFO - 'OS': 'Windows_NT',
14:47:57 INFO - 'OURDRIVE': 'C:',
14:47:57 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
14:47:57 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
14:47:57 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
14:47:57 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
14:47:57 INFO - 'PROCESSOR_LEVEL': '6',
14:47:57 INFO - 'PROCESSOR_REVISION': '1e05',
14:47:57 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
14:47:57 INFO - 'PROGRAMFILES': 'C:\\Program Files',
14:47:57 INFO - 'PROMPT': '$P$G',
14:47:57 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
14:47:57 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
14:47:57 INFO - 'PUBLIC': 'C:\\Users\\Public',
14:47:57 INFO - 'PWD': 'C:\\slave\\test',
14:47:57 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
14:47:57 INFO - 'SLAVEDIR': 'C:\\slave\\',
14:47:57 INFO - 'SYSTEMDRIVE': 'C:',
14:47:57 INFO - 'SYSTEMROOT': 'C:\\windows',
14:47:57 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
14:47:57 INFO - 'TEST1': 'testie',
14:47:57 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
14:47:57 INFO - 'USERDOMAIN': 'T-W732-IX-131',
14:47:57 INFO - 'USERNAME': 'cltbld',
14:47:57 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
14:47:57 INFO - 'WINDIR': 'C:\\windows',
14:47:57 INFO - 'WINDOWS_TRACING_FLAGS': '3',
14:47:57 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
14:47:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
14:47:57 INFO - Ignoring indexes: https://pypi.python.org/simple/
14:47:57 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages
14:47:57 INFO - Cleaning up...
14:47:57 INFO - Return code: 0
14:47:57 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv
14:47:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
14:47:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
14:47:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
14:47:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
14:47:57 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 0x018F0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01995F20>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0189B0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019A4E90>, '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 0x012EA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F015C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-131', 'USERDOMAIN': 'T-W732-IX-131', '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-131', '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
14:47:57 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
14:47:57 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
14:47:57 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
14:47:57 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
14:47:57 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
14:47:57 INFO - 'COMPUTERNAME': 'T-W732-IX-131',
14:47:57 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
14:47:57 INFO - 'CYGWINBASE': 'C:\\cygwin',
14:47:57 INFO - 'DCLOCATION': 'SCL3',
14:47:57 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
14:47:57 INFO - 'FP_NO_HOST_CHECK': 'NO',
14:47:57 INFO - 'HOMEDRIVE': 'C:',
14:47:57 INFO - 'HOMEPATH': '\\Users\\cltbld',
14:47:57 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
14:47:57 INFO - 'KTS_VERSION': '1.19c',
14:47:57 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
14:47:57 INFO - 'LOGONSERVER': '\\\\T-W732-IX-131',
14:47:57 INFO - 'MONDIR': 'C:\\Monitor_config\\',
14:47:57 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
14:47:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
14:47:57 INFO - 'MOZ_NO_REMOTE': '1',
14:47:57 INFO - 'NO_EM_RESTART': '1',
14:47:57 INFO - 'NUMBER_OF_PROCESSORS': '8',
14:47:57 INFO - 'OS': 'Windows_NT',
14:47:57 INFO - 'OURDRIVE': 'C:',
14:47:57 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
14:47:57 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
14:47:57 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
14:47:57 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
14:47:57 INFO - 'PROCESSOR_LEVEL': '6',
14:47:57 INFO - 'PROCESSOR_REVISION': '1e05',
14:47:57 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
14:47:57 INFO - 'PROGRAMFILES': 'C:\\Program Files',
14:47:57 INFO - 'PROMPT': '$P$G',
14:47:57 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
14:47:57 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
14:47:57 INFO - 'PUBLIC': 'C:\\Users\\Public',
14:47:57 INFO - 'PWD': 'C:\\slave\\test',
14:47:57 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
14:47:57 INFO - 'SLAVEDIR': 'C:\\slave\\',
14:47:57 INFO - 'SYSTEMDRIVE': 'C:',
14:47:57 INFO - 'SYSTEMROOT': 'C:\\windows',
14:47:57 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
14:47:57 INFO - 'TEST1': 'testie',
14:47:57 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
14:47:57 INFO - 'USERDOMAIN': 'T-W732-IX-131',
14:47:57 INFO - 'USERNAME': 'cltbld',
14:47:57 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
14:47:57 INFO - 'WINDIR': 'C:\\windows',
14:47:57 INFO - 'WINDOWS_TRACING_FLAGS': '3',
14:47:57 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
14:47:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
14:47:57 INFO - Ignoring indexes: https://pypi.python.org/simple/
14:47:57 INFO - Downloading/unpacking blobuploader==1.2.4
14:47:57 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
14:47:57 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
14:47:58 INFO - Downloading blobuploader-1.2.4.tar.gz
14:47:59 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader
14:47:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4)
14:47:59 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
14:47:59 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
14:47:59 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests
14:47:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4)
14:47:59 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
14:47:59 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
14:48:00 INFO - Downloading docopt-0.6.1.tar.gz
14:48:00 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt
14:48:00 INFO - Installing collected packages: blobuploader, requests, docopt
14:48:00 INFO - Running setup.py install for blobuploader
14:48:00 INFO - Running setup.py install for requests
14:48:01 INFO - Running setup.py install for docopt
14:48:01 INFO - Successfully installed blobuploader requests docopt
14:48:01 INFO - Cleaning up...
14:48:02 INFO - Return code: 0
14:48:02 INFO - Installing None into virtualenv C:\slave\test\build/venv
14:48:02 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')]}
14:48:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
14:48:02 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')]}
14:48:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
14:48:02 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 0x018F0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01995F20>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0189B0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019A4E90>, '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 0x012EA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F015C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-131', 'USERDOMAIN': 'T-W732-IX-131', '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-131', '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
14:48:02 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
14:48:02 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
14:48:02 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
14:48:02 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
14:48:02 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
14:48:02 INFO - 'COMPUTERNAME': 'T-W732-IX-131',
14:48:02 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
14:48:02 INFO - 'CYGWINBASE': 'C:\\cygwin',
14:48:02 INFO - 'DCLOCATION': 'SCL3',
14:48:02 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
14:48:02 INFO - 'FP_NO_HOST_CHECK': 'NO',
14:48:02 INFO - 'HOMEDRIVE': 'C:',
14:48:02 INFO - 'HOMEPATH': '\\Users\\cltbld',
14:48:02 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
14:48:02 INFO - 'KTS_VERSION': '1.19c',
14:48:02 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
14:48:02 INFO - 'LOGONSERVER': '\\\\T-W732-IX-131',
14:48:02 INFO - 'MONDIR': 'C:\\Monitor_config\\',
14:48:02 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
14:48:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
14:48:02 INFO - 'MOZ_NO_REMOTE': '1',
14:48:02 INFO - 'NO_EM_RESTART': '1',
14:48:02 INFO - 'NUMBER_OF_PROCESSORS': '8',
14:48:02 INFO - 'OS': 'Windows_NT',
14:48:02 INFO - 'OURDRIVE': 'C:',
14:48:02 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;',
14:48:02 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
14:48:02 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
14:48:02 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
14:48:02 INFO - 'PROCESSOR_LEVEL': '6',
14:48:02 INFO - 'PROCESSOR_REVISION': '1e05',
14:48:02 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
14:48:02 INFO - 'PROGRAMFILES': 'C:\\Program Files',
14:48:02 INFO - 'PROMPT': '$P$G',
14:48:02 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
14:48:02 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
14:48:02 INFO - 'PUBLIC': 'C:\\Users\\Public',
14:48:02 INFO - 'PWD': 'C:\\slave\\test',
14:48:02 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
14:48:02 INFO - 'SLAVEDIR': 'C:\\slave\\',
14:48:02 INFO - 'SYSTEMDRIVE': 'C:',
14:48:02 INFO - 'SYSTEMROOT': 'C:\\windows',
14:48:02 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
14:48:02 INFO - 'TEST1': 'testie',
14:48:02 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
14:48:02 INFO - 'USERDOMAIN': 'T-W732-IX-131',
14:48:02 INFO - 'USERNAME': 'cltbld',
14:48:02 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
14:48:02 INFO - 'WINDIR': 'C:\\windows',
14:48:02 INFO - 'WINDOWS_TRACING_FLAGS': '3',
14:48:02 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
14:48:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
14:48:02 INFO - Ignoring indexes: https://pypi.python.org/simple/
14:48:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
14:48:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jkdxau-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
14:48:03 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
14:48:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ix2jdj-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
14:48:03 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
14:48:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vsjhvt-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
14:48:03 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
14:48:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cu3w5s-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
14:48:03 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
14:48:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pwclp1-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
14:48:03 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
14:48:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-z5bixd-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
14:48:03 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
14:48:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fngxgh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
14:48:04 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
14:48:04 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cmok6c-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
14:48:04 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
14:48:04 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-2kh69g-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
14:48:04 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
14:48:04 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-e_9sph-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
14:48:04 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
14:48:04 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rplvub-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
14:48:04 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
14:48:04 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kmml1a-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
14:48:04 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
14:48:04 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-0lr6nt-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
14:48:04 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
14:48:04 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pyxyyi-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
14:48:05 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
14:48:05 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wsze9l-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
14:48:05 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
14:48:05 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-unpj90-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
14:48:05 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
14:48:05 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-d7vgjg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
14:48:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
14:48:05 INFO - Running setup.py install for manifestparser
14:48:05 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts
14:48:05 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts
14:48:06 INFO - Running setup.py install for mozcrash
14:48:06 INFO - Running setup.py install for mozdebug
14:48:06 INFO - Running setup.py install for mozdevice
14:48:07 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts
14:48:07 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts
14:48:07 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts
14:48:07 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts
14:48:08 INFO - Running setup.py install for mozfile
14:48:08 INFO - Running setup.py install for mozhttpd
14:48:08 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts
14:48:08 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts
14:48:08 INFO - Running setup.py install for mozinfo
14:48:09 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts
14:48:09 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts
14:48:09 INFO - Running setup.py install for mozInstall
14:48:09 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts
14:48:09 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts
14:48:09 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts
14:48:09 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts
14:48:09 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts
14:48:09 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts
14:48:09 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts
14:48:09 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts
14:48:09 INFO - Running setup.py install for mozleak
14:48:09 INFO - Running setup.py install for mozlog
14:48:10 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts
14:48:10 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts
14:48:10 INFO - Running setup.py install for moznetwork
14:48:11 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts
14:48:11 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts
14:48:11 INFO - Running setup.py install for mozprocess
14:48:11 INFO - Running setup.py install for mozprofile
14:48:11 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts
14:48:11 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts
14:48:11 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts
14:48:11 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts
14:48:11 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts
14:48:11 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts
14:48:12 INFO - Running setup.py install for mozrunner
14:48:12 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts
14:48:12 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts
14:48:13 INFO - Running setup.py install for mozscreenshot
14:48:13 INFO - Running setup.py install for moztest
14:48:13 INFO - Running setup.py install for mozversion
14:48:13 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts
14:48:13 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts
14:48:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion
14:48:14 INFO - Cleaning up...
14:48:14 INFO - Return code: 0
14:48:14 INFO - Installing None into virtualenv C:\slave\test\build/venv
14:48:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
14:48:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
14:48:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
14:48:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
14:48:14 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 0x018F0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01995F20>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0189B0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019A4E90>, '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 0x012EA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F015C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-131', 'USERDOMAIN': 'T-W732-IX-131', '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-131', '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
14:48:14 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
14:48:14 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
14:48:14 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
14:48:14 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
14:48:14 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
14:48:14 INFO - 'COMPUTERNAME': 'T-W732-IX-131',
14:48:14 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
14:48:14 INFO - 'CYGWINBASE': 'C:\\cygwin',
14:48:14 INFO - 'DCLOCATION': 'SCL3',
14:48:14 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
14:48:14 INFO - 'FP_NO_HOST_CHECK': 'NO',
14:48:14 INFO - 'HOMEDRIVE': 'C:',
14:48:14 INFO - 'HOMEPATH': '\\Users\\cltbld',
14:48:14 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
14:48:14 INFO - 'KTS_VERSION': '1.19c',
14:48:14 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
14:48:14 INFO - 'LOGONSERVER': '\\\\T-W732-IX-131',
14:48:14 INFO - 'MONDIR': 'C:\\Monitor_config\\',
14:48:14 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
14:48:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
14:48:14 INFO - 'MOZ_NO_REMOTE': '1',
14:48:14 INFO - 'NO_EM_RESTART': '1',
14:48:14 INFO - 'NUMBER_OF_PROCESSORS': '8',
14:48:14 INFO - 'OS': 'Windows_NT',
14:48:14 INFO - 'OURDRIVE': 'C:',
14:48: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;',
14:48:14 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
14:48:14 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
14:48:14 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
14:48:14 INFO - 'PROCESSOR_LEVEL': '6',
14:48:14 INFO - 'PROCESSOR_REVISION': '1e05',
14:48:14 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
14:48:14 INFO - 'PROGRAMFILES': 'C:\\Program Files',
14:48:14 INFO - 'PROMPT': '$P$G',
14:48:14 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
14:48:14 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
14:48:14 INFO - 'PUBLIC': 'C:\\Users\\Public',
14:48:14 INFO - 'PWD': 'C:\\slave\\test',
14:48:14 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
14:48:14 INFO - 'SLAVEDIR': 'C:\\slave\\',
14:48:14 INFO - 'SYSTEMDRIVE': 'C:',
14:48:14 INFO - 'SYSTEMROOT': 'C:\\windows',
14:48:14 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
14:48:14 INFO - 'TEST1': 'testie',
14:48:14 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
14:48:14 INFO - 'USERDOMAIN': 'T-W732-IX-131',
14:48:14 INFO - 'USERNAME': 'cltbld',
14:48:14 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
14:48:14 INFO - 'WINDIR': 'C:\\windows',
14:48:14 INFO - 'WINDOWS_TRACING_FLAGS': '3',
14:48:14 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
14:48:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
14:48:14 INFO - Ignoring indexes: https://pypi.python.org/simple/
14:48:14 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
14:48:14 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bcadlr-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
14:48:14 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))
14:48:14 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
14:48:14 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-i_vlfd-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
14:48:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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))
14:48:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
14:48:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fe3veq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
14:48:15 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))
14:48:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
14:48:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ywxuse-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
14:48:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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))
14:48:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
14:48:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vwtnru-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
14:48:15 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))
14:48:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
14:48:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-tt0jyh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
14:48:15 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))
14:48:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
14:48:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-d_na3s-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
14:48:15 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))
14:48:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
14:48:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xsazhk-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
14:48:15 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))
14:48:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
14:48:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-janu5i-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
14:48:15 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))
14:48:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
14:48:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lnsc1d-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
14:48:16 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))
14:48:16 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
14:48:16 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ce_jsh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
14:48:16 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))
14:48:16 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
14:48:16 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dtv5nl-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
14:48:16 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))
14:48:16 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
14:48:16 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ae4xnl-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
14:48:16 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))
14:48:16 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
14:48:16 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-sleyp2-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
14:48:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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))
14:48:16 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
14:48:16 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bo9xwc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
14:48:16 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))
14:48:16 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
14:48:16 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-2qtotz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
14:48:16 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))
14:48:16 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
14:48:16 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-1ifwim-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
14:48:17 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))
14:48:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==1.0->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2))
14:48:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==1.0->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2))
14:48:17 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))
14:48:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.49->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4))
14:48:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.49->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4))
14:48:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10))
14:48:17 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
14:48:17 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
14:48:18 INFO - Downloading blessings-1.6.tar.gz
14:48:18 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings
14:48:18 INFO - Installing collected packages: blessings
14:48:18 INFO - Running setup.py install for blessings
14:48:18 INFO - Successfully installed blessings
14:48:18 INFO - Cleaning up...
14:48:21 INFO - Return code: 0
14:48:21 INFO - Done creating virtualenv C:\slave\test\build/venv.
14:48:21 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
14:48:21 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
14:48:21 INFO - Reading from file tmpfile_stdout
14:48:21 INFO - Using _rmtree_windows ...
14:48:21 INFO - Using _rmtree_windows ...
14:48:21 INFO - Current package versions:
14:48:21 INFO - blessings == 1.6
14:48:21 INFO - blobuploader == 1.2.4
14:48:21 INFO - docopt == 0.6.1
14:48:21 INFO - functools32 == 3.2.3-2
14:48:21 INFO - jsonschema == 2.5.1
14:48:21 INFO - manifestparser == 1.1
14:48:21 INFO - mozInstall == 1.12
14:48:21 INFO - mozcrash == 1.0
14:48:21 INFO - mozdebug == 0.1
14:48:21 INFO - mozdevice == 0.49
14:48:21 INFO - mozfile == 1.2
14:48:21 INFO - mozhttpd == 0.7
14:48:21 INFO - mozinfo == 0.9
14:48:21 INFO - mozleak == 0.1
14:48:21 INFO - mozlog == 3.4
14:48:21 INFO - moznetwork == 0.27
14:48:21 INFO - mozprocess == 0.23
14:48:21 INFO - mozprofile == 0.28
14:48:21 INFO - mozrunner == 6.13
14:48:21 INFO - mozscreenshot == 0.1
14:48:21 INFO - mozsystemmonitor == 0.3
14:48:21 INFO - moztest == 0.7
14:48:21 INFO - mozversion == 1.4
14:48:21 INFO - psutil == 3.1.1
14:48:21 INFO - requests == 1.2.3
14:48:21 INFO - Installing None into virtualenv C:\slave\test\build/venv
14:48:21 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')]}
14:48:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
14:48:21 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')]}
14:48:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
14:48:21 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 0x018F0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01995F20>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0189B0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019A4E90>, '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 0x012EA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F015C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-131', 'USERDOMAIN': 'T-W732-IX-131', '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-131', '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
14:48:21 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
14:48:21 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
14:48:21 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
14:48:21 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
14:48:21 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
14:48:21 INFO - 'COMPUTERNAME': 'T-W732-IX-131',
14:48:21 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
14:48:21 INFO - 'CYGWINBASE': 'C:\\cygwin',
14:48:21 INFO - 'DCLOCATION': 'SCL3',
14:48:21 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
14:48:21 INFO - 'FP_NO_HOST_CHECK': 'NO',
14:48:21 INFO - 'HOMEDRIVE': 'C:',
14:48:21 INFO - 'HOMEPATH': '\\Users\\cltbld',
14:48:21 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
14:48:21 INFO - 'KTS_VERSION': '1.19c',
14:48:21 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
14:48:21 INFO - 'LOGONSERVER': '\\\\T-W732-IX-131',
14:48:21 INFO - 'MONDIR': 'C:\\Monitor_config\\',
14:48:21 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
14:48:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
14:48:21 INFO - 'MOZ_NO_REMOTE': '1',
14:48:21 INFO - 'NO_EM_RESTART': '1',
14:48:21 INFO - 'NUMBER_OF_PROCESSORS': '8',
14:48:21 INFO - 'OS': 'Windows_NT',
14:48:21 INFO - 'OURDRIVE': 'C:',
14:48:21 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;',
14:48:21 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
14:48:21 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
14:48:21 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
14:48:21 INFO - 'PROCESSOR_LEVEL': '6',
14:48:21 INFO - 'PROCESSOR_REVISION': '1e05',
14:48:21 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
14:48:21 INFO - 'PROGRAMFILES': 'C:\\Program Files',
14:48:21 INFO - 'PROMPT': '$P$G',
14:48:21 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
14:48:21 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
14:48:21 INFO - 'PUBLIC': 'C:\\Users\\Public',
14:48:21 INFO - 'PWD': 'C:\\slave\\test',
14:48:21 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
14:48:21 INFO - 'SLAVEDIR': 'C:\\slave\\',
14:48:21 INFO - 'SYSTEMDRIVE': 'C:',
14:48:21 INFO - 'SYSTEMROOT': 'C:\\windows',
14:48:21 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
14:48:21 INFO - 'TEST1': 'testie',
14:48:21 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
14:48:21 INFO - 'USERDOMAIN': 'T-W732-IX-131',
14:48:21 INFO - 'USERNAME': 'cltbld',
14:48:21 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
14:48:21 INFO - 'WINDIR': 'C:\\windows',
14:48:21 INFO - 'WINDOWS_TRACING_FLAGS': '3',
14:48:21 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
14:48:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
14:48:22 INFO - Ignoring indexes: https://pypi.python.org/simple/
14:48:22 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))
14:48:22 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))
14:48:22 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))
14:48:22 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))
14:48:22 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))
14:48:22 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))
14:48:22 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))
14:48:22 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))
14:48:22 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))
14:48:22 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))
14:48:22 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))
14:48:22 INFO - Cleaning up...
14:48:22 INFO - Return code: 0
14:48:22 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv
14:48:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
14:48:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
14:48:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
14:48:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
14:48:22 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 0x018F0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01995F20>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0189B0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019A4E90>, '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 0x012EA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F015C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-131', 'USERDOMAIN': 'T-W732-IX-131', '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-131', '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
14:48:22 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
14:48:22 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
14:48:22 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
14:48:22 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
14:48:22 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
14:48:22 INFO - 'COMPUTERNAME': 'T-W732-IX-131',
14:48:22 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
14:48:22 INFO - 'CYGWINBASE': 'C:\\cygwin',
14:48:22 INFO - 'DCLOCATION': 'SCL3',
14:48:22 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
14:48:22 INFO - 'FP_NO_HOST_CHECK': 'NO',
14:48:22 INFO - 'HOMEDRIVE': 'C:',
14:48:22 INFO - 'HOMEPATH': '\\Users\\cltbld',
14:48:22 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
14:48:22 INFO - 'KTS_VERSION': '1.19c',
14:48:22 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
14:48:22 INFO - 'LOGONSERVER': '\\\\T-W732-IX-131',
14:48:22 INFO - 'MONDIR': 'C:\\Monitor_config\\',
14:48:22 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
14:48:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
14:48:22 INFO - 'MOZ_NO_REMOTE': '1',
14:48:22 INFO - 'NO_EM_RESTART': '1',
14:48:22 INFO - 'NUMBER_OF_PROCESSORS': '8',
14:48:22 INFO - 'OS': 'Windows_NT',
14:48:22 INFO - 'OURDRIVE': 'C:',
14:48:22 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;',
14:48:22 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
14:48:22 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
14:48:22 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
14:48:22 INFO - 'PROCESSOR_LEVEL': '6',
14:48:22 INFO - 'PROCESSOR_REVISION': '1e05',
14:48:22 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
14:48:22 INFO - 'PROGRAMFILES': 'C:\\Program Files',
14:48:22 INFO - 'PROMPT': '$P$G',
14:48:22 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
14:48:22 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
14:48:22 INFO - 'PUBLIC': 'C:\\Users\\Public',
14:48:22 INFO - 'PWD': 'C:\\slave\\test',
14:48:22 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
14:48:22 INFO - 'SLAVEDIR': 'C:\\slave\\',
14:48:22 INFO - 'SYSTEMDRIVE': 'C:',
14:48:22 INFO - 'SYSTEMROOT': 'C:\\windows',
14:48:22 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
14:48:22 INFO - 'TEST1': 'testie',
14:48:22 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
14:48:22 INFO - 'USERDOMAIN': 'T-W732-IX-131',
14:48:22 INFO - 'USERNAME': 'cltbld',
14:48:22 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
14:48:22 INFO - 'WINDIR': 'C:\\windows',
14:48:22 INFO - 'WINDOWS_TRACING_FLAGS': '3',
14:48:22 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
14:48:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
14:48:22 INFO - Ignoring indexes: https://pypi.python.org/simple/
14:48:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages
14:48:22 INFO - Cleaning up...
14:48:22 INFO - Return code: 0
14:48:22 INFO - Running post-action listener: _resource_record_post_action
14:48:22 INFO - Running post-action listener: _start_resource_monitoring
14:48:22 INFO - Starting resource monitoring.
14:48:22 INFO - [mozharness: 2016-12-02 22:48:22.766000Z] Finished create-virtualenv step (success)
14:48:22 INFO - [mozharness: 2016-12-02 22:48:22.766000Z] Running install step.
14:48:22 INFO - Running pre-action listener: _resource_record_pre_action
14:48:22 INFO - Running main action method: install
14:48:22 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
14:48:22 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
14:48:23 INFO - Reading from file tmpfile_stdout
14:48:23 INFO - Using _rmtree_windows ...
14:48:23 INFO - Using _rmtree_windows ...
14:48:23 INFO - Detecting whether we're running mozinstall >=1.0...
14:48:23 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h']
14:48:23 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h
14:48:23 INFO - Reading from file tmpfile_stdout
14:48:23 INFO - Output received:
14:48:23 INFO - Usage: mozinstall-script.py [options] installer
14:48:23 INFO - Options:
14:48:23 INFO - -h, --help show this help message and exit
14:48:23 INFO - -d DEST, --destination=DEST
14:48:23 INFO - Directory to install application into. [default:
14:48:23 INFO - "C:\slave\test"]
14:48:23 INFO - --app=APP Application being installed. [default: firefox]
14:48:23 INFO - Using _rmtree_windows ...
14:48:23 INFO - Using _rmtree_windows ...
14:48:23 INFO - mkdir: C:\slave\test\build\application
14:48:23 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-53.0a1.en-US.win32.zip', '--destination', 'C:\\slave\\test\\build\\application']
14:48:23 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py C:\slave\test\build\firefox-53.0a1.en-US.win32.zip --destination C:\slave\test\build\application
14:48:25 INFO - Reading from file tmpfile_stdout
14:48:25 INFO - Output received:
14:48:25 INFO - C:\slave\test\build\application\firefox\firefox.exe
14:48:25 INFO - Using _rmtree_windows ...
14:48:25 INFO - Using _rmtree_windows ...
14:48:25 INFO - Running post-action listener: _resource_record_post_action
14:48:25 INFO - [mozharness: 2016-12-02 22:48:25.744000Z] Finished install step (success)
14:48:25 INFO - [mozharness: 2016-12-02 22:48:25.744000Z] Running run-tests step.
14:48:25 INFO - Running pre-action listener: _resource_record_pre_action
14:48:25 INFO - Running main action method: run_tests
14:48:25 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version']
14:48:25 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version
14:48:25 INFO - Python 2.7.3
14:48:25 INFO - Return code: 0
14:48:25 INFO - grabbing minidump binary from tooltool
14:48:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
14:48:25 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 0x019A4E90>, '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 0x012EA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F015C0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1
14:48:25 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
14:48:25 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
14:48:25 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
14:48:25 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'...
14:48:27 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmpwze6vu
14:48:27 INFO - INFO - File integrity verified, renaming tmpwze6vu to win32-minidump_stackwalk.exe
14:48:27 INFO - Return code: 0
14:48:27 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755
14:48:27 INFO - mkdir: C:\slave\test\build\blobber_upload_dir
14:48:27 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir
14:48:27 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe
14:48:27 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir
14:48:27 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KQoUN0VTTkyg2Ia4JKY-oQ/artifacts/public/build/firefox-53.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-131', '--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
14:48:27 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/KQoUN0VTTkyg2Ia4JKY-oQ/artifacts/public/build/firefox-53.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-131 --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
14:48:27 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
14:48:27 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
14:48:27 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
14:48:27 INFO - 'COMPUTERNAME': 'T-W732-IX-131',
14:48:27 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
14:48:27 INFO - 'CYGWINBASE': 'C:\\cygwin',
14:48:27 INFO - 'DCLOCATION': 'SCL3',
14:48:27 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
14:48:27 INFO - 'FP_NO_HOST_CHECK': 'NO',
14:48:27 INFO - 'HOMEDRIVE': 'C:',
14:48:27 INFO - 'HOMEPATH': '\\Users\\cltbld',
14:48:27 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
14:48:27 INFO - 'KTS_VERSION': '1.19c',
14:48:27 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
14:48:27 INFO - 'LOGONSERVER': '\\\\T-W732-IX-131',
14:48:27 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir',
14:48:27 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe',
14:48:27 INFO - 'MONDIR': 'C:\\Monitor_config\\',
14:48:27 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
14:48:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
14:48:27 INFO - 'MOZ_NO_REMOTE': '1',
14:48:27 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir',
14:48:27 INFO - 'NO_EM_RESTART': '1',
14:48:27 INFO - 'NUMBER_OF_PROCESSORS': '8',
14:48:27 INFO - 'OS': 'Windows_NT',
14:48:27 INFO - 'OURDRIVE': 'C:',
14:48:27 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
14:48:27 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
14:48:27 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
14:48:27 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
14:48:27 INFO - 'PROCESSOR_LEVEL': '6',
14:48:27 INFO - 'PROCESSOR_REVISION': '1e05',
14:48:27 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
14:48:27 INFO - 'PROGRAMFILES': 'C:\\Program Files',
14:48:27 INFO - 'PROMPT': '$P$G',
14:48:27 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
14:48:27 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
14:48:27 INFO - 'PUBLIC': 'C:\\Users\\Public',
14:48:27 INFO - 'PWD': 'C:\\slave\\test',
14:48:27 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos',
14:48:27 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
14:48:27 INFO - 'SLAVEDIR': 'C:\\slave\\',
14:48:27 INFO - 'SYSTEMDRIVE': 'C:',
14:48:27 INFO - 'SYSTEMROOT': 'C:\\windows',
14:48:27 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
14:48:27 INFO - 'TEST1': 'testie',
14:48:27 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
14:48:27 INFO - 'USERDOMAIN': 'T-W732-IX-131',
14:48:27 INFO - 'USERNAME': 'cltbld',
14:48:27 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
14:48:27 INFO - 'WINDIR': 'C:\\windows',
14:48:27 INFO - 'WINDOWS_TRACING_FLAGS': '3',
14:48:27 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
14:48:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
14:48:27 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KQoUN0VTTkyg2Ia4JKY-oQ/artifacts/public/build/firefox-53.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-131', '--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
14:48:28 INFO - mozversion application_buildid: 20161202130943
14:48:28 INFO - mozversion application_changeset: bfa85d23df57c8a1db17c99b267667becc1c4afd
14:48:28 INFO - mozversion application_display_name: Nightly
14:48:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
14:48:28 INFO - mozversion application_name: Firefox
14:48:28 INFO - mozversion application_remotingname: firefox
14:48:28 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central
14:48:28 INFO - mozversion application_vendor: Mozilla
14:48:28 INFO - mozversion application_version: 53.0a1
14:48:28 INFO - mozversion platform_buildid: 20161202130943
14:48:28 INFO - mozversion platform_changeset: bfa85d23df57c8a1db17c99b267667becc1c4afd
14:48:28 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central
14:48:28 INFO - mozversion platform_version: 53.0a1
14:48:28 INFO - using testdate: 1480718908
14:48:28 INFO - actual date: 1480718908
14:48:28 INFO - starting webserver on 'localhost:49855'
14:48:28 INFO - SUITE-START | Running 2 tests
14:48:28 INFO - TEST-START | dromaeo_css
14:48:28 INFO - operating with platform_type : w7_
14:48:28 INFO - Initialising browser for dromaeo_css test...
14:48:28 INFO - TEST-INFO | started process 3364 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpozyzls\profile http://localhost:49855/getInfo.html)
14:48:35 INFO - PROCESS | 3364 | __metrics Screen width/height:1600/1200
14:48:35 INFO - PROCESS | 3364 | colorDepth:24
14:48:35 INFO - PROCESS | 3364 | Browser inner width/height: 1010/674
14:48:35 INFO - PROCESS | 3364 | __metrics
14:48:36 INFO - PROCESS | 3364 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows"
14:48:36 INFO - PROCESS | 3364 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
14:48:37 INFO - TEST-INFO | 3364: exit 0
14:48:37 INFO - Browser initialized.
14:48:37 INFO - Running cycle 1/1 for dromaeo_css test...
14:48:37 INFO - TEST-INFO | started process 2968 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpozyzls\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1)
14:48:38 INFO - PROCESS | 2968 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0
14:48:49 INFO - PROCESS | 2968 | RSS: Main: 138358784
14:48:49 INFO - PROCESS | 2968 |
14:49:41 INFO - PROCESS | 2968 | 1480718981171 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]
14:51:54 INFO - PROCESS | 2968 | Cycle 1(1): loaded http://localhost:49855/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49855/tests/dromaeo/cssquery-ext.html)
14:51:56 INFO - PROCESS | 2968 | RSS: Main: 197382144
14:51:56 INFO - PROCESS | 2968 |
14:54:59 INFO - PROCESS | 2968 | Cycle 1(1): loaded http://localhost:49855/tests/dromaeo/cssquery-ext.html (next: http://localhost:49855/tests/dromaeo/cssquery-jquery.html)
14:55:00 INFO - PROCESS | 2968 | RSS: Main: 205139968
14:55:00 INFO - PROCESS | 2968 |
14:58:02 INFO - PROCESS | 2968 | Cycle 1(1): loaded http://localhost:49855/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49855/tests/dromaeo/cssquery-mootools.html)
14:58:03 INFO - PROCESS | 2968 | RSS: Main: 146251776
14:58:03 INFO - PROCESS | 2968 |
15:01:05 INFO - PROCESS | 2968 | Cycle 1(1): loaded http://localhost:49855/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49855/tests/dromaeo/cssquery-prototype.html)
15:01:06 INFO - PROCESS | 2968 | RSS: Main: 145924096
15:01:06 INFO - PROCESS | 2968 |
15:04:09 INFO - PROCESS | 2968 | Cycle 1(1): loaded http://localhost:49855/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49855/tests/dromaeo/cssquery-yui.html)
15:04:09 INFO - PROCESS | 2968 | RSS: Main: 144777216
15:04:09 INFO - PROCESS | 2968 |
15:07:12 INFO - PROCESS | 2968 | Cycle 1(1): loaded http://localhost:49855/tests/dromaeo/cssquery-yui.html (next: http://localhost:49855/tests/dromaeo/cssquery-dojo.html)
15:07:13 INFO - PROCESS | 2968 | RSS: Main: 193658880
15:07:13 INFO - PROCESS | 2968 |
15:07:13 INFO - PROCESS | 2968 | __start_tp_report
15:07:13 INFO - PROCESS | 2968 | _x_x_mozilla_page_load
15:07:13 INFO - PROCESS | 2968 | _x_x_mozilla_page_load_details
15:07:13 INFO - PROCESS | 2968 | |i|pagename|runs|
15:07:13 INFO - PROCESS | 2968 | |0;dojo.html;2298.046310781026;2306.861588304702;2318.258157389635;2319.211468100845;2330.1727886600256;3842.2699687059476;3875.2636998970174;3937.665298449628;3941.5861334559854;3948.94076588851;3802.676772474343;3816.236752649474;3957.445957565943;3964.781936993464;4055.594440555943;3413.078468813419;3414.112330793999;3414.8292585370696;3426.9828650856834;3436.3470940521347;3418.7606867519285;3426.7601267911255;3470.2192006798396;3507.2810073934784;3512.7365447591405;3434.1586311353753;3443.93238096191;3474.357243909872;3489.494023366623;3534.028142260886;3479.460683594047;3482.059843842174;3547.9113022174392;3549.8580056797696;3556.448750443685;3428.434308339121;3472.878449254264;3522.5772907251294;3522.6125126236093;3543.7342199335153;3421.367047096295;3822.7515211511163;3868.168343806095;3879.650831425185;3903.843846246175;3998.200359928026;4044.474218351625;4064.552899181088;4079.9392158038827;4144.48193975753;4061.5938406159444;4062.045419326456;4126.42230087788;4169.478815148106;4177;3720.925581488355;3800.4109363049047;3832.9425058624142;3878.728489005799;3881.825317860703;4056.7363121397607;4199.0552125772065;4234.1108367242605;4239.152169566038;4246.023414614594;4021.4168945503325;4093.529244136903;4107.774072426046;4113.01287690958;4113.650339721157;4037.7577345359373;4081.2653722330265;4081.3061779497557;4165.291900376943;4180.7909604519655;4078.204750073707;4081.163361510883;4090.6318431341324;4094.140230551558;4095.6518695910586;4043.4743483347565;4083.4487344208746;4087;4088.6115818997146;4090.959090409058;2930.399268150024;2961.9111426657237;2974.5240761478494;2980.2847316644284;3004.1137864330076;2696.6898806637487;2746.093789049609;2749.312671832042;2752.0642981386427;2753.366725653071;3797.373433383461;3808.562015368268;3835.8657446989223;3938.1926705025403;3938.6061393860386;3922.431247469101;4009.2382447334912;4065.918681626351;4073.103917138225;4166.979165104155;3700.5744339400776;3775.3770627846097;3853.460515527828;3898.590647981978;3898.688104951597;3475.0269924421204;3549.716022718176;3557.3952428087287;3562.340966921128;3567.6789088981595;4249.80875860587;4252.170826688766;4310.353446982977;4338.891527711832;4338.913221735611;4153.543110257809;4160.188763191149;4230.5134909485805;4237.194932962727;4238.131183107517;4061.593840615915;4091.365838295069;4201.684873634551;4315.180115777992;4323.52441231458;3962.1481381503113;3969;4004.139110091228;4012.0170558213426;4024.8993775154686;3947.3684210526176;3968.40473928913;4075.2664520386616;4147.35715964014;4153.605407486284;3869.897079332377;3958.524977002778;4026.557078721397;4037.1723796621036;4039.0710136668145;3792.6207379261855;3793.430985352219;3800.220954704224;3814.904627384338;3840.5967373425387;3719.3119272934596;3869.7484663496784;3884.9417258740577;3892.844286228519;3895.610438956082;4010.6390424860733;4028.8992775180855;4056.148208876169;4063.4717486726536;4098.139390727981;3858.5948475409677;3980.024893901013;4019.799010049427;4047.352423612208;4060.4315395845197
15:07:13 INFO - PROCESS | 2968 | |1;ext.html;514.9459306772789;529.2537522093846;544.1565573361291;552.2241251042284;555.0453220460811;33665.158371040736;34105.14737131573;35301.940941771754;37918.241635167346;38191.809040954824;34408.827955860186;34604.826975865086;34752.131196720096;34912.82543587285;34995.30009399811;6240.001599744041;6843;6967.407770339501;6968.895466568006;6980.9301906981045;6018;8514.276286515653;8629.741107766777;8827.293816494664;8914.598843052047;8583.699570515033;8927.41971771836;8929.73210803677;8930.330225233101;8934;6180.814575562696;8933.106689331047;9032.277417806625;9046.638134474613;9049.638014479413;6193.752249910043;7981.96009019957;8451.577421128919;8517.020542637552;8525.531095789729;32560.511592326133;32979;33300.33399332;33340.49989250163;33347.499787503206;2619.607058941155;2632.907848225322;2649.165512863461;2964.9407011859644;3133.7336326412283;24845;25187.24438266855;25224;25302.36744081394;25675.871620641778;2673.679158500973;2710.5527587947963;2711.227300219428;2728.113363156962;2775.278427608816;201175;204344;204761;206415.9999999985;206705;45509.31736023896;45641.31538026932;45916.081678366245;45926.081478370244;45926.77036614795;48461.27308090381;48560;48563.51436485661;48577.75711121422;48619.75690121527;10639.73400664974;11059.557617695202;11083.002529772199;11093.168012399103;11100.278481898651;6838.931610683929;7064.1876184238445;8156.592170391456;8161.714339998071;8238.176182381834;15114.319855606303;15407.38370465169;15621.453249136453;15942.521724348288;15987.163080860742;5840.620359676607;5910.172575839386;5928.377520360386;5976.340528464878;5978.192943952511;700.8913618389158;792.6393490961599;799.9680012799423;805.8589746794288;859.4757198109148;23247.651285230735;24291.63562546563;24356.147534836196;24378.1467648635;24458.877705611358;792.834533236142;821.1788211788212;851.6550796927254;875.2691502595404;902.4810733827944;12837.550685725955;12845.678858028625;13004.739905201843;13014.284214368112;13117.60647180586;305451;305612;309331;309544;311155;38144;41615.37576936348;41659.000919889804;42240.366394504104;42270.36594451086;942.5146049784366;1031.3863251365426;1042.0673497220098;1060.984085238722;1061.5435362794074;24265.39336516601;24698.959433265616;24854.627180591942;25004.999800008525;25079.122230721838;34815;35209;36247.27505449929;36321.27357452783;36393.454098189075;173069;174564;175573;378883;379385;19312;31127.68872311248;31999.200019999687;32277.677223228406;32318.67681323251;32143.19642008875;35844.820775895954;36175;36246;36250;30126.548101778928;30376.544351834298;30430.695693042788;30808.229794255323;31059.37881242408;28891.711082889742;30027.630054368263;30232;30358.57131429436;31310;29947.550786737782;30055.549166762077;30761.230969225948;30872.536911946787;30882.69117308798;26684.59973100444;28001.159965201077;29856;29907.55138672878;29956;21942;22845.65731514062;22873.88563057174;22901.88549057244;23029.53940921206
15:07:13 INFO - PROCESS | 2968 | |2;jquery.html;270.8063734429882;278.7909068198851;287.1801008121812;293.51130367937384;294.1689726522574;10726.839097413535;14801.185934773583;14837.925810370947;14906.329215185315;15754.606134846634;12626.810597841017;12744.362781860893;12977.156484828503;13117.47530098795;13170.209787412761;4622.953770462294;4873.025394921012;4876.244182151754;4881.974785295092;4884.365032545782;3483.5471388719557;3618.149734812317;3647.3981793004123;3678.062094165997;4771.594414474756;3656.9943265601905;3667.266546690659;3678;4983.878627308844;5554.555635549146;5696.77212911483;5708.828735137931;5723.141528770676;5732.971335143297;5738.626989245707;3965.801709914522;4886.071646387175;4919.508049195088;4920.975395123001;4931.901361972777;9322.48726320052;9565.186959108483;9775.41347519144;9790.412575245507;9803.264755143393;1483.2657834372055;1488.3451281436242;1523.54293711886;1628.6091338078895;1660.0205878531604;11586.30482171064;11891;11913.344766037864;12098;12201;1338.7188690374935;1344.46893477077;1345.8317710286215;1352.7565038293103;1376.1192836584594;58970;61791;62676;63392;63518.68240658767;8203.30271926881;8258.339332853357;8345.040320363114;8569.700060497977;8751.518666473277;9818.65634702782;10246.129079028366;10709.946450267698;10791.892081079246;11475.598354057734;5486.3690675572025;5614.775408983676;5636.577256705681;5703.914441283384;5767.019606666877;5148.481198046586;5208.218767184877;5255.684658920401;5265.815696450606;5351.892962140735;3550.3609350317192;3607.134287770916;3632.6185750496347;3662.1760103976394;3704.333220020368;1485.3984136424765;1540.191399515241;1562.8280889102189;1575.527341797479;1595.2422599265487;755.833716582351;772.764306886405;794.9284564389233;838.6687258532845;862.6247582301719;14500.492482763053;14951.177685227416;15203.391864325304;15291.617709557127;15490.148041857581;735.2537174767615;763.4808330335314;790.8774140008308;801.9558924259222;824.1593574553922;8259.091499935003;8472.279856212292;8612.913870861337;8664.436811607226;8837.204651581389;217715;260586;268925;269082;270886;74041.62979185069;75269.24730752732;76396.61801691067;77060.61469692616;77956.22043779603;755.1504557372955;773.2499475508747;774.5159275452842;775.4494309040645;775.6005657086605;13614.591562253148;14069.366878490287;14220.288985550475;14284.785728213878;14564.126152430888;33765.92213401611;36092;36340.27319453543;36967;37678.62321376751;41039.38440923329;47924;49158.26262605992;49437;49685.50314496953;5492.588055895744;5741.196232527366;6216.285127210248;6221.160143380585;6477.995910633859;8396.622152003296;10055.547500362354;10176.542055607664;10286.948565257126;10508.406925111072;8679.088695686862;8680;8764.649414023368;8835.24900383449;8845.867311990454;7881.211878812073;8220.506769593843;8373.539455330016;8427;8855.335849811161;8384.958075209584;8909.955450222707;9057.501837399006;9235.76910577241;9238.076192380977;5411.810586629449;5865.736041878211;5931.495822855106;6680.131582894192;7055.153381594037;6418.807435776934;8663.783405414915;8664.046954834837;8736.475811451333;8740.737777866674
15:07:13 INFO - PROCESS | 2968 | |3;mootools.html;994.9950549944554;1143.6283207957415;1147.6958605969419;1196.4376742930822;1286.343964578065;4988.750562471875;5412.864678383037;5555.638883472581;5575.526080283171;5581.079121944879;5476.644018138818;5668.263125793641;5672.461116193966;5679.943200567993;5691.630044047134;1413.9787903181461;1449.5868677426936;1585.643230273188;1602.2949902043122;1612.4356475233692;1576.8817338699587;1621.4568119679861;1626.1462732065702;1635.779169812078;1636;1582.4224158182237;1595.9680806383865;1610.5651474101985;1611.6784236925726;1612.5001249612599;1519.8480151984768;1548.0634216299202;1570.5916461720035;1575.165162463896;1576.676781259839;1549.6125968507872;1591.633924197441;1593.3148746039199;1593.4582242037764;1594.689035638051;5415.566754659618;5448.073827449303;5522.723863806834;5524.751386187591;5527.723613819293;2991.3568582754597;3020;3070.631524217086;3072.016954574537;3072.170513961218;2051.3948385226395;2172.1202912820177;2203.294945617403;2204.933851984443;2207.724034495688;655.8557117434157;678.972841086356;680.7583307925714;683.5693513086774;686.4199751210219;6729.057931889588;6842.828929276759;7008.579485230903;7020.508564400494;7051.1186101737285;2562.6412302277695;2750.422411293611;2793.539066054119;2801.3136781488665;2810.761085307731;6142.78500252489;6175.876482470415;6190.133381326618;6202.627842329474;6215.160953271341;2998.385331007138;3146.496560550301;3157.952630710541;3159.352332771776;3161.873525059018;2536.4039450729065;2612.3469132716823;2620.71172171061;2810.4800611886867;2857.1999840044405;2342.9179978700663;2429.3197904586746;2490.203134996784;2569.588865781466;2576.2786419802487;793.4723408933111;793.8809178623137;805.923437273458;807.1081454992267;810.6514198894414;567.7133047810829;695.384584642595;717.5300178383168;718.2027948976631;730.5470608222936;3783.8675646352244;3814.9809250953567;3842.9615703842605;3891.922161556753;3944.4280579315837;697.2748341724549;713.9500234983501;722.2849379114743;735.1839458201392;752.9247075292536;2482.1188478090016;2505.2359030495873;2515.5472015037103;2519.1183085920147;2594.494073655619;6166.753329866755;6494.740210391531;6506.772262970773;6518.9895566187315;6542.574732642363;6513.576617519846;6546.410823025951;6548.1814773153355;6552.312007239267;6552.6068435894;5203.739813009334;5211.687298762011;5230.424653288135;5239.973800130975;5257.894842103136;3822.388417853241;3849.1339448623835;3869;3893.6301051400073;4027.5771044041126;5988.131720900358;6075.787347442818;6143.354947730424;6154.753809847556;6159.23009623797;6151.138840562415;6180.938190618216;6269.247690277196;6272.5922815016875;6281.371862813682;4816.8073277069925;4847.951520484891;4855.805767769391;4866.78099485531;4875.951240487691;5253.13323301651;5346.893062138814;5359.758810853599;5371.811986580451;5375.3012108425655;4758.928616070831;4893.437254715611;4946.332245147003;4950.45544990058;4967.627427942846;4763.976180119077;4786.1624215762795;4826.831060912851;4861.9546797438725;4870.439899411613;4525.343825145402;4579.793909274024;4596.379488768974;4614.953850461587;4626.86119416418;3486;3522.559680039995;3525.3125640499857;3552.289542091644;3561.0919215599856;3476.704480119117;3489.8429570669887;3503.2468019375956;3516.876909308162;3531.9293614128087
15:07:13 INFO - PROCESS | 2968 | |4;prototype.html;201.52440241031172;213.47485186441352;222.7838996173712;222.96321107017343;224.7112460488273;4241.342591898257;4369.497507786606;4461.687681862271;4469.083837813253;4521.932171017437;4383.342498625208;4414.874207077199;4422.270325396305;4431.977840110795;4444.866654000377;3067.3251884585356;3148.4962406015043;3235.1265158407214;3239.967600324002;3248.59392575928;3142.19873932147;3212.779143925305;3214.7267482264037;3232.692894175061;3247.5778148840736;3197.9520307195294;3211.1490455029434;3211.935761284773;3220.1788543921266;3220.3881262560158;3011.8945836895605;3146.229173852421;3179.728108756493;3200.9839950800215;3202.551642770014;2667.8932842686268;2680.7802449885257;2753.0501976817964;2761.226856480189;2763.8894444222205;4142.71001029928;4163.042500224965;4257.425247591566;4257.74453532789;4265.9786701066605;3686.4101743721144;3711.9814400928094;3738.102855314732;3738.196287798109;3739.4016957286703;4397.428334316518;4477.574630410106;4491.595756381941;4499.437570303712;4512.571305725951;2836.4752520783723;3096.2970366298673;3111.175538482304;3114.2837147455984;3114.844257787124;4703.200455922467;4705.270942853172;4711.010687755575;4733.95266047342;4736.384270044872;2996.895108671186;3003.8648260828313;3012.171652795455;3018.758499320049;3019;2969.198316454588;2993.236724635204;3022.335086281015;3027.9848600756854;3046.1775320663296;3558.377283975294;3614.7831130132276;3634.8000859952426;3638.799866007395;3642.5993140754495;4313.439252897103;4434.312681534368;4456.955430445654;4566.32869771181;4587.564181402761;1023.7235946294608;1035.342557476008;1044.1385856668278;1044.6918159143072;1048.5491238767252;663.3797399431547;677.8271540757075;679.7654809090856;680.6460640466929;681.5059082165391;4096.692748043909;4212.347086201644;4216.430781844473;4223.873283801429;4254.127903779718;4542.841000564964;4556.544345565417;4567.862964111082;4573.588377046081;4573.6569757268335;4214.620684138442;4247.915041699211;4254.255505286625;4254.936175957363;4275.764832934156;2416.743293487412;2421.7093948726265;2434.464417828075;2441.2432146034785;2444.154663229286;4566.383538222298;4736.976315118402;4859.205956574983;4868.805247790119;4897.191963326082;4778.5699287064335;4779.665423420397;4805.687630304019;4807.543283388072;4820.927686084711;4791.161546729239;4810.811080958156;4858.611311095104;4866.489018653061;4902.583280421204;4701.529847015271;4738.483685220696;4741.881452963704;4763.666543342071;4768.260919557474;4406.845760398371;4623.468301145277;4663.114008338405;4668.719876807402;4675.158471475031;4814.542618451242;4816.662833601615;4829.9517004830905;4853.757312134449;4889.63327750413;3057.3579548295106;3064.540318952175;3064.892728754483;3068.98465507671;3074.892378766822;4685.2269375554015;4688.859334219979;4696.990147118386;4699.76501174933;4700.670953033392;4225.260579398531;4317.481902171759;4332.241857674957;4480;4552.248878935071;4527.592516673383;4535.591796738309;4542.500324964318;4551.772411379484;4565.315202719486;4351.564843515623;4397.7581233031215;4408.008198155389;4443.8889027773275;4480.215962206666;4561.764735279756;4581.335706322498;4590.563896429834;4591.989762252299;4595.839145629887;4382.386465894713;4459.3979812724865;4470;4498.460184777848;4530.139273538017
15:07:13 INFO - PROCESS | 2968 | |5;yui.html;564.2185572981422;608.3733754233141;631.1290419221474;631.775719619535;640.5068097564873;12170;12673.176243544163;12681.17572357796;12748.80876786849;12760.29818359991;11560.59537916173;12107.213031152969;12111.636650900487;12117.939410302935;12130.636080917586;486.3167250013719;490.8920037591724;622.8909940760371;631.6715308039817;631.7915088020965;490.35273439060455;500.5945184400647;620.7641096383368;628.1833616298816;630.3822254190895;662.8177251255919;663.2273401487262;663.4559661077918;663.5255792108642;663.7709990053423;639.5203597302024;651.7295322441181;657.8848701477223;658.7793089315085;660.957037792542;374.30753106752564;377.2172741561244;459.8160735705711;472.78724573941867;483.22442479820086;12061;12360.258384496869;12456.564020259248;12905;12907.548235811795;10958.397288149148;11009.889901100967;11560.768784624344;11578.71053223676;12122.090843186796;10614.893851061468;10845.240833141683;10866.511007004703;10990.450477476094;10994.890051099466;3700.1674623209565;3761.0597350662333;3781.716371272166;3809.257194847006;3823.4073718573663;55045.724771376284;61811;65673.67163164202;66131;68687.65656171687;9285.489298088534;9334.253259739204;9365.203957663538;9423.34036617444;9573.856392154123;14569.781453278209;15222.467213647691;15250.161241131844;15319.234038298264;16601.91699041521;5727.169560413717;6273.381582430488;6312.368763123651;6350.68246587678;6365.395287447685;3383.509391138271;3384.9323013540084;3407.1141503208846;3492.6158122606494;3525.682688557991;2221.2114699281847;2233.229535810143;2329.6039673255586;2340.520193360357;2344.4490544721975;382.0715660943933;387.2120235321108;389.8499077855046;391.0243941366309;392.5485691454793;514.4161376837242;516.7622893468971;523.1629392971215;526.3788729299464;536.6055948877572;6138.14066030756;6574.671266436659;6869.420033392348;6930.549514281555;6935.433889460932;442.41601086566067;510.8927125303654;516.3493997563022;517.5833454069435;543.7852048440843;3149.4803357445767;3149.5748074010176;3150.889718859829;3188.5536024956523;3272.2146684795953;60309;60340;60386.69806651027;62361.68819155875;63363;53658.73170634122;54683.179752303746;55589;55739.44260557424;55762.44237557572;47652.76173619179;48704;49063.75468122708;50239.24641130313;50349;6292.433680968735;6482.416582507597;6541.6075035498025;6595;6688.866222675422;32740.67259327376;36588.81705591455;36658.63341366552;36838.44742328921;37085.25829483449;44935.77532112318;46010.3285023663;46497.76751116223;47922.5207747918;48290;9154.221891139061;9166.770830729285;9178.219851312713;9377;9421.528923553931;10156.289059765746;10584.841227381441;10595;10641.521131548943;10726.946365268435;9353.766155846159;9569.712908612753;9583.281253905845;9730.854037189307;9853.35376166519;9651.806963860543;9956.303058786105;9975.850362244428;10008.749781255236;10146.847797283195;9273.86089208676;9292.95353523228;9649.083337082966;9674.322797404115;9737.17234035115;8287.751367458986;8448.577571121297;8864.955675221583;9158.95420522893;9298;8128.796780080545;8157.428979971345;8286.751397457845;8357.247847693738;8380.58097095155
15:07:13 INFO - PROCESS | 2968 | __end_tp_report
15:07:13 INFO - PROCESS | 2968 | __start_cc_report
15:07:13 INFO - PROCESS | 2968 | _x_x_mozilla_cycle_collect,1825
15:07:13 INFO - PROCESS | 2968 | __end_cc_report
15:07:13 INFO - PROCESS | 2968 | __startTimestamp1480720033121__endTimestamp
15:07:13 INFO - PROCESS | 2968 |
15:07:13 INFO - PROCESS | 2968 | ------- Summary: start -------
15:07:13 INFO - PROCESS | 2968 | Number of tests: 6
15:07:13 INFO - PROCESS | 2968 |
15:07:13 INFO - PROCESS | 2968 | [#0] dojo.html Cycles:165 Average:3797.28 Median:3938.61 stddev:438.72 (11.1%) stddev-sans-first:424.01
15:07:13 INFO - PROCESS | 2968 | Values: 2298.0 2306.9 2318.3 2319.2 2330.2 3842.3 3875.3 3937.7 3941.6 3948.9 3802.7 3816.2 3957.4 3964.8 4055.6 3413.1 3414.1 3414.8 3427.0 3436.3 3418.8 3426.8 3470.2 3507.3 3512.7 3434.2 3443.9 3474.4 3489.5 3534.0 3479.5 3482.1 3547.9 3549.9 3556.4 3428.4 3472.9 3522.6 3522.6 3543.7 3421.4 3822.8 3868.2 3879.7 3903.8 3998.2 4044.5 4064.6 4079.9 4144.5 4061.6 4062.0 4126.4 4169.5 4177.0 3720.9 3800.4 3832.9 3878.7 3881.8 4056.7 4199.1 4234.1 4239.2 4246.0 4021.4 4093.5 4107.8 4113.0 4113.7 4037.8 4081.3 4081.3 4165.3 4180.8 4078.2 4081.2 4090.6 4094.1 4095.7 4043.5 4083.4 4087.0 4088.6 4091.0 2930.4 2961.9 2974.5 2980.3 3004.1 2696.7 2746.1 2749.3 2752.1 2753.4 3797.4 3808.6 3835.9 3938.2 3938.6 3922.4 4009.2 4065.9 4073.1 4167.0 3700.6 3775.4 3853.5 3898.6 3898.7 3475.0 3549.7 3557.4 3562.3 3567.7 4249.8 4252.2 4310.4 4338.9 4338.9 4153.5 4160.2 4230.5 4237.2 4238.1 4061.6 4091.4 4201.7 4315.2 4323.5 3962.1 3969.0 4004.1 4012.0 4024.9 3947.4 3968.4 4075.3 4147.4 4153.6 3869.9 3958.5 4026.6 4037.2 4039.1 3792.6 3793.4 3800.2 3814.9 3840.6 3719.3 3869.7 3884.9 3892.8 3895.6 4010.6 4028.9 4056.1 4063.5 4098.1 3858.6 3980.0 4019.8 4047.4 4060.4
15:07:13 INFO - PROCESS | 2968 |
15:07:13 INFO - PROCESS | 2968 | [#1] ext.html Cycles:180 Average:39668.84 Median:33320.42 stddev:70136.01 (210.5%) stddev-sans-first:70271.15
15:07:13 INFO - PROCESS | 2968 | Values: 514.9 529.3 544.2 552.2 555.0 33665.2 34105.1 35301.9 37918.2 38191.8 34408.8 34604.8 34752.1 34912.8 34995.3 6240.0 6843.0 6967.4 6968.9 6980.9 6018.0 8514.3 8629.7 8827.3 8914.6 8583.7 8927.4 8929.7 8930.3 8934.0 6180.8 8933.1 9032.3 9046.6 9049.6 6193.8 7982.0 8451.6 8517.0 8525.5 32560.5 32979.0 33300.3 33340.5 33347.5 2619.6 2632.9 2649.2 2964.9 3133.7 24845.0 25187.2 25224.0 25302.4 25675.9 2673.7 2710.6 2711.2 2728.1 2775.3 201175.0 204344.0 204761.0 206416.0 206705.0 45509.3 45641.3 45916.1 45926.1 45926.8 48461.3 48560.0 48563.5 48577.8 48619.8 10639.7 11059.6 11083.0 11093.2 11100.3 6838.9 7064.2 8156.6 8161.7 8238.2 15114.3 15407.4 15621.5 15942.5 15987.2 5840.6 5910.2 5928.4 5976.3 5978.2 700.9 792.6 800.0 805.9 859.5 23247.7 24291.6 24356.1 24378.1 24458.9 792.8 821.2 851.7 875.3 902.5 12837.6 12845.7 13004.7 13014.3 13117.6 305451.0 305612.0 309331.0 309544.0 311155.0 38144.0 41615.4 41659.0 42240.4 42270.4 942.5 1031.4 1042.1 1061.0 1061.5 24265.4 24699.0 24854.6 25005.0 25079.1 34815.0 35209.0 36247.3 36321.3 36393.5 173069.0 174564.0 175573.0 378883.0 379385.0 19312.0 31127.7 31999.2 32277.7 32318.7 32143.2 35844.8 36175.0 36246.0 36250.0 30126.5 30376.5 30430.7 30808.2 31059.4 28891.7 30027.6 30232.0 30358.6 31310.0 29947.6 30055.5 30761.2 30872.5 30882.7 26684.6 28001.2 29856.0 29907.6 29956.0 21942.0 22845.7 22873.9 22901.9 23029.5
15:07:13 INFO - PROCESS | 2968 |
15:07:13 INFO - PROCESS | 2968 | [#2] jquery.html Cycles:180 Average:19063.99 Median:27334.69 stddev:43832.47 (160.4%) stddev-sans-first:43932.72
15:07:13 INFO - PROCESS | 2968 | Values: 270.8 278.8 287.2 293.5 294.2 10726.8 14801.2 14837.9 14906.3 15754.6 12626.8 12744.4 12977.2 13117.5 13170.2 4623.0 4873.0 4876.2 4882.0 4884.4 3483.5 3618.1 3647.4 3678.1 4771.6 3657.0 3667.3 3678.0 4983.9 5554.6 5696.8 5708.8 5723.1 5733.0 5738.6 3965.8 4886.1 4919.5 4921.0 4931.9 9322.5 9565.2 9775.4 9790.4 9803.3 1483.3 1488.3 1523.5 1628.6 1660.0 11586.3 11891.0 11913.3 12098.0 12201.0 1338.7 1344.5 1345.8 1352.8 1376.1 58970.0 61791.0 62676.0 63392.0 63518.7 8203.3 8258.3 8345.0 8569.7 8751.5 9818.7 10246.1 10709.9 10791.9 11475.6 5486.4 5614.8 5636.6 5703.9 5767.0 5148.5 5208.2 5255.7 5265.8 5351.9 3550.4 3607.1 3632.6 3662.2 3704.3 1485.4 1540.2 1562.8 1575.5 1595.2 755.8 772.8 794.9 838.7 862.6 14500.5 14951.2 15203.4 15291.6 15490.1 735.3 763.5 790.9 802.0 824.2 8259.1 8472.3 8612.9 8664.4 8837.2 217715.0 260586.0 268925.0 269082.0 270886.0 74041.6 75269.2 76396.6 77060.6 77956.2 755.2 773.2 774.5 775.4 775.6 13614.6 14069.4 14220.3 14284.8 14564.1 33765.9 36092.0 36340.3 36967.0 37678.6 41039.4 47924.0 49158.3 49437.0 49685.5 5492.6 5741.2 6216.3 6221.2 6478.0 8396.6 10055.5 10176.5 10286.9 10508.4 8679.1 8680.0 8764.6 8835.2 8845.9 7881.2 8220.5 8373.5 8427.0 8855.3 8385.0 8910.0 9057.5 9235.8 9238.1 5411.8 5865.7 5931.5 6680.1 7055.2 6418.8 8663.8 8664.0 8736.5 8740.7
15:07:13 INFO - PROCESS | 2968 |
15:07:13 INFO - PROCESS | 2968 | [#3] mootools.html Cycles:180 Average:3617.24 Median:4775.07 stddev:1953.55 (40.9%) stddev-sans-first:1949.10
15:07:13 INFO - PROCESS | 2968 | Values: 995.0 1143.6 1147.7 1196.4 1286.3 4988.8 5412.9 5555.6 5575.5 5581.1 5476.6 5668.3 5672.5 5679.9 5691.6 1414.0 1449.6 1585.6 1602.3 1612.4 1576.9 1621.5 1626.1 1635.8 1636.0 1582.4 1596.0 1610.6 1611.7 1612.5 1519.8 1548.1 1570.6 1575.2 1576.7 1549.6 1591.6 1593.3 1593.5 1594.7 5415.6 5448.1 5522.7 5524.8 5527.7 2991.4 3020.0 3070.6 3072.0 3072.2 2051.4 2172.1 2203.3 2204.9 2207.7 655.9 679.0 680.8 683.6 686.4 6729.1 6842.8 7008.6 7020.5 7051.1 2562.6 2750.4 2793.5 2801.3 2810.8 6142.8 6175.9 6190.1 6202.6 6215.2 2998.4 3146.5 3158.0 3159.4 3161.9 2536.4 2612.3 2620.7 2810.5 2857.2 2342.9 2429.3 2490.2 2569.6 2576.3 793.5 793.9 805.9 807.1 810.7 567.7 695.4 717.5 718.2 730.5 3783.9 3815.0 3843.0 3891.9 3944.4 697.3 714.0 722.3 735.2 752.9 2482.1 2505.2 2515.5 2519.1 2594.5 6166.8 6494.7 6506.8 6519.0 6542.6 6513.6 6546.4 6548.2 6552.3 6552.6 5203.7 5211.7 5230.4 5240.0 5257.9 3822.4 3849.1 3869.0 3893.6 4027.6 5988.1 6075.8 6143.4 6154.8 6159.2 6151.1 6180.9 6269.2 6272.6 6281.4 4816.8 4848.0 4855.8 4866.8 4876.0 5253.1 5346.9 5359.8 5371.8 5375.3 4758.9 4893.4 4946.3 4950.5 4967.6 4764.0 4786.2 4826.8 4862.0 4870.4 4525.3 4579.8 4596.4 4615.0 4626.9 3486.0 3522.6 3525.3 3552.3 3561.1 3476.7 3489.8 3503.2 3516.9 3531.9
15:07:13 INFO - PROCESS | 2968 |
15:07:13 INFO - PROCESS | 2968 | [#4] prototype.html Cycles:180 Average:3743.62 Median:4360.53 stddev:1176.47 (27.0%) stddev-sans-first:1149.34
15:07:13 INFO - PROCESS | 2968 | Values: 201.5 213.5 222.8 223.0 224.7 4241.3 4369.5 4461.7 4469.1 4521.9 4383.3 4414.9 4422.3 4432.0 4444.9 3067.3 3148.5 3235.1 3240.0 3248.6 3142.2 3212.8 3214.7 3232.7 3247.6 3198.0 3211.1 3211.9 3220.2 3220.4 3011.9 3146.2 3179.7 3201.0 3202.6 2667.9 2680.8 2753.1 2761.2 2763.9 4142.7 4163.0 4257.4 4257.7 4266.0 3686.4 3712.0 3738.1 3738.2 3739.4 4397.4 4477.6 4491.6 4499.4 4512.6 2836.5 3096.3 3111.2 3114.3 3114.8 4703.2 4705.3 4711.0 4734.0 4736.4 2996.9 3003.9 3012.2 3018.8 3019.0 2969.2 2993.2 3022.3 3028.0 3046.2 3558.4 3614.8 3634.8 3638.8 3642.6 4313.4 4434.3 4457.0 4566.3 4587.6 1023.7 1035.3 1044.1 1044.7 1048.5 663.4 677.8 679.8 680.6 681.5 4096.7 4212.3 4216.4 4223.9 4254.1 4542.8 4556.5 4567.9 4573.6 4573.7 4214.6 4247.9 4254.3 4254.9 4275.8 2416.7 2421.7 2434.5 2441.2 2444.2 4566.4 4737.0 4859.2 4868.8 4897.2 4778.6 4779.7 4805.7 4807.5 4820.9 4791.2 4810.8 4858.6 4866.5 4902.6 4701.5 4738.5 4741.9 4763.7 4768.3 4406.8 4623.5 4663.1 4668.7 4675.2 4814.5 4816.7 4830.0 4853.8 4889.6 3057.4 3064.5 3064.9 3069.0 3074.9 4685.2 4688.9 4697.0 4699.8 4700.7 4225.3 4317.5 4332.2 4480.0 4552.2 4527.6 4535.6 4542.5 4551.8 4565.3 4351.6 4397.8 4408.0 4443.9 4480.2 4561.8 4581.3 4590.6 4592.0 4595.8 4382.4 4459.4 4470.0 4498.5 4530.1
15:07:13 INFO - PROCESS | 2968 |
15:07:13 INFO - PROCESS | 2968 | [#5] yui.html Cycles:180 Average:13863.16 Median:517.17 stddev:18053.72 (3490.8%) stddev-sans-first:18076.75
15:07:13 INFO - PROCESS | 2968 | Values: 564.2 608.4 631.1 631.8 640.5 12170.0 12673.2 12681.2 12748.8 12760.3 11560.6 12107.2 12111.6 12117.9 12130.6 486.3 490.9 622.9 631.7 631.8 490.4 500.6 620.8 628.2 630.4 662.8 663.2 663.5 663.5 663.8 639.5 651.7 657.9 658.8 661.0 374.3 377.2 459.8 472.8 483.2 12061.0 12360.3 12456.6 12905.0 12907.5 10958.4 11009.9 11560.8 11578.7 12122.1 10614.9 10845.2 10866.5 10990.5 10994.9 3700.2 3761.1 3781.7 3809.3 3823.4 55045.7 61811.0 65673.7 66131.0 68687.7 9285.5 9334.3 9365.2 9423.3 9573.9 14569.8 15222.5 15250.2 15319.2 16601.9 5727.2 6273.4 6312.4 6350.7 6365.4 3383.5 3384.9 3407.1 3492.6 3525.7 2221.2 2233.2 2329.6 2340.5 2344.4 382.1 387.2 389.8 391.0 392.5 514.4 516.8 523.2 526.4 536.6 6138.1 6574.7 6869.4 6930.5 6935.4 442.4 510.9 516.3 517.6 543.8 3149.5 3149.6 3150.9 3188.6 3272.2 60309.0 60340.0 60386.7 62361.7 63363.0 53658.7 54683.2 55589.0 55739.4 55762.4 47652.8 48704.0 49063.8 50239.2 50349.0 6292.4 6482.4 6541.6 6595.0 6688.9 32740.7 36588.8 36658.6 36838.4 37085.3 44935.8 46010.3 46497.8 47922.5 48290.0 9154.2 9166.8 9178.2 9377.0 9421.5 10156.3 10584.8 10595.0 10641.5 10726.9 9353.8 9569.7 9583.3 9730.9 9853.4 9651.8 9956.3 9975.9 10008.7 10146.8 9273.9 9293.0 9649.1 9674.3 9737.2 8287.8 8448.6 8865.0 9159.0 9298.0 8128.8 8157.4 8286.8 8357.2 8380.6
15:07:13 INFO - PROCESS | 2968 | -------- Summary: end --------
15:07:13 INFO - PROCESS | 2968 |
15:07:13 INFO - TEST-INFO | 2968: exit 0
15:07:14 INFO - TEST-OK | dromaeo_css | took 1125897ms
15:07:14 INFO - TEST-START | kraken
15:07:14 INFO - operating with platform_type : w7_
15:07:14 INFO - Initialising browser for kraken test...
15:07:14 INFO - TEST-INFO | started process 3236 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpapfaeb\profile http://localhost:49855/getInfo.html)
15:07:19 INFO - PROCESS | 3236 | __metrics Screen width/height:1600/1200
15:07:19 INFO - PROCESS | 3236 | colorDepth:24
15:07:19 INFO - PROCESS | 3236 | Browser inner width/height: 1010/674
15:07:19 INFO - PROCESS | 3236 | __metrics
15:07:20 INFO - PROCESS | 3236 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows"
15:07:20 INFO - PROCESS | 3236 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
15:07:20 INFO - PROCESS | 3236 | console.error:
15:07:20 INFO - PROCESS | 3236 | Message: Error: Connection closed before committing the transaction.
15:07:20 INFO - PROCESS | 3236 | Stack:
15:07:20 INFO - PROCESS | 3236 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:932:23
15:07:20 INFO - PROCESS | 3236 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:813:7
15:07:20 INFO - PROCESS | 3236 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:744:11
15:07:20 INFO - PROCESS | 3236 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:776:7
15:07:20 INFO - PROCESS | 3236 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:711:7
15:07:20 INFO - PROCESS | 3236 | TaskImpl_run@resource://gre/modules/Task.jsm:324:15
15:07:20 INFO - PROCESS | 3236 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:550:9
15:07:20 INFO - PROCESS | 3236 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15
15:07:20 INFO - PROCESS | 3236 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:386:5
15:07:20 INFO - PROCESS | 3236 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29
15:07:20 INFO - PROCESS | 3236 | Barrier/this.client.addBlocker/promise13.2%
15:08:22 INFO - TinderboxPrint: I/O read bytes / time
95,199,744 / 1,467,340
15:08:22 INFO - TinderboxPrint: I/O write bytes / time
483,102,208 / 3,709,710
15:08:22 INFO - TinderboxPrint: CPU idle
8,323.8 (86.8%)
15:08:22 INFO - TinderboxPrint: CPU user
1,230.0 (12.8%)
15:08:22 INFO - install - Wall time: 3s; CPU: 11.0%; Read bytes: 0; Write bytes: 34753536; Read time: 0; Write time: 142430
15:08:22 INFO - run-tests - Wall time: 1196s; CPU: 13.0%; Read bytes: 95166976; Write bytes: 409667584; Read time: 1441170; Write time: 3310800
15:08:22 INFO - Running post-run listener: _upload_blobber_files
15:08:22 INFO - Blob upload gear active.
15:08:22 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir.
15:08:22 INFO - Files from C:\slave\test\build\blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com
15:08:22 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', 'Firefox-Non-PGO', '-d', 'C:\\slave\\test\\build\\blobber_upload_dir', '--output-manifest', 'C:\\slave\\test\\build\\uploaded_files.json']
15:08:22 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 Firefox-Non-PGO -d C:\slave\test\build\blobber_upload_dir --output-manifest C:\slave\test\build\uploaded_files.json
15:08:23 INFO - (blobuploader) - INFO - Open directory for files ...
15:08:23 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log ...
15:08:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
15:08:23 INFO - (blobuploader) - INFO - Uploading, attempt #1.
15:08:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded
15:08:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
15:08:24 INFO - (blobuploader) - INFO - Done attempting.
15:08:24 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log ...
15:08:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
15:08:24 INFO - (blobuploader) - INFO - Uploading, attempt #1.
15:08:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded
15:08:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
15:08:25 INFO - (blobuploader) - INFO - Done attempting.
15:08:25 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ...
15:08:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
15:08:25 INFO - (blobuploader) - INFO - Uploading, attempt #1.
15:08:33 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
15:08:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
15:08:33 INFO - (blobuploader) - INFO - Done attempting.
15:08:33 INFO - (blobuploader) - INFO - Iteration through files over.
15:08:33 INFO - Return code: 0
15:08:33 INFO - rmtree: C:\slave\test\build\uploaded_files.json
15:08:33 INFO - Using _rmtree_windows ...
15:08:33 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1
15:08:33 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9371a582cc30534f300231d2b71ab10e432b0683c87ed7306b324b6eee8b8a04486c18d7410122e79fe1efc47a04ae40ceb9af4a00baacb818a5d15e91535b73", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/46b8ff0f6b14c59102889537205f1d7af709cffac661f3ffab71a89645e9664539c619f55ffa3dd82f7278c1096604a95b77306e72a532b69635defb7cacd42a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/85e6e92e00fa3f970701cb45010066c92e4b31d80ebbb443d3b534db7cbc6be7f28cd6db9a3bd543895a534e5b904425a1b762c01d94354fda22a31913f6adac"}
15:08:33 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files
15:08:33 INFO - Writing to file C:\slave\test\properties\blobber_files
15:08:33 INFO - Contents:
15:08:33 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9371a582cc30534f300231d2b71ab10e432b0683c87ed7306b324b6eee8b8a04486c18d7410122e79fe1efc47a04ae40ceb9af4a00baacb818a5d15e91535b73", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/46b8ff0f6b14c59102889537205f1d7af709cffac661f3ffab71a89645e9664539c619f55ffa3dd82f7278c1096604a95b77306e72a532b69635defb7cacd42a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/85e6e92e00fa3f970701cb45010066c92e4b31d80ebbb443d3b534db7cbc6be7f28cd6db9a3bd543895a534e5b904425a1b762c01d94354fda22a31913f6adac"}
15:08:33 INFO - Running post-run listener: copy_logs_to_upload_dir
15:08:33 INFO - Copying logs to upload dir...
15:08:33 INFO - mkdir: C:\slave\test\build\upload\logs
15:08:33 INFO - Copying logs to upload dir...
15:08:33 INFO - Using _rmtree_windows ...
15:08:33 INFO - Using _rmtree_windows ...
15:08:33 INFO - Using _rmtree_windows ...
15:08:33 INFO - Using _rmtree_windows ...
15:08:33 INFO - Using _rmtree_windows ...
15:08:33 INFO - Using _rmtree_windows ...
15:08:33 INFO - Using _rmtree_windows ...
program finished with exit code 0
elapsedTime=1413.664000
========= master_lag: 0.10 =========
========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 33 secs) (at 2016-12-02 15:08:33.912586) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-02 15:08:33.914445) =========
'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-131
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-131
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-131
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/Firefox-Non-PGO/sha512/9371a582cc30534f300231d2b71ab10e432b0683c87ed7306b324b6eee8b8a04486c18d7410122e79fe1efc47a04ae40ceb9af4a00baacb818a5d15e91535b73", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/46b8ff0f6b14c59102889537205f1d7af709cffac661f3ffab71a89645e9664539c619f55ffa3dd82f7278c1096604a95b77306e72a532b69635defb7cacd42a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/85e6e92e00fa3f970701cb45010066c92e4b31d80ebbb443d3b534db7cbc6be7f28cd6db9a3bd543895a534e5b904425a1b762c01d94354fda22a31913f6adac"}
build_url:https://queue.taskcluster.net/v1/task/KQoUN0VTTkyg2Ia4JKY-oQ/artifacts/public/build/firefox-53.0a1.en-US.win32.zip
program finished with exit code 0
elapsedTime=0.102000
build_url: 'https://queue.taskcluster.net/v1/task/KQoUN0VTTkyg2Ia4JKY-oQ/artifacts/public/build/firefox-53.0a1.en-US.win32.zip'
blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9371a582cc30534f300231d2b71ab10e432b0683c87ed7306b324b6eee8b8a04486c18d7410122e79fe1efc47a04ae40ceb9af4a00baacb818a5d15e91535b73", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/46b8ff0f6b14c59102889537205f1d7af709cffac661f3ffab71a89645e9664539c619f55ffa3dd82f7278c1096604a95b77306e72a532b69635defb7cacd42a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/85e6e92e00fa3f970701cb45010066c92e4b31d80ebbb443d3b534db7cbc6be7f28cd6db9a3bd543895a534e5b904425a1b762c01d94354fda22a31913f6adac"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-02 15:08:34.036562) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-02 15:08:34.037112) =========
'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-131
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-131
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-131
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-12-02 15:08:34.151826) =========
========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-12-02 15:08:34.152660) =========
========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-12-02 15:08:34.633357) =========
========= Total master_lag: 0.28 =========