builder: fx-team_win7_ix_test-dromaeojs-pgo
slave: t-w732-ix-106
starttime: 1475009661.34
results: success (0)
buildid: 20160927093046
builduid: adc29abbf3454bf588fd2342cc44952b
revision: b0d4f06aabd64fe182c1f145d70c90681d91028f
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-27 13:54:21.341982) =========
master: http://buildbot-master119.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-27 13:54:21.342411) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-27 13:54:21.342685) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-27 13:54:21.511344) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-27 13:54:21.511718) =========
'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-106
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-106
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-106
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
--13:54:22-- 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,141 (12K) [text/x-python]
0K .......... . 100% 21.13 MB/s
13:54:23 (21.13 MB/s) - `archiver_client.py' saved [12141/12141]
program finished with exit code 0
elapsedTime=1.380000
========= master_lag: 0.34 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-27 13:54:23.227388) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 14 secs) (at 2016-09-27 13:54:23.227701) =========
'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-106
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-106
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-106
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=14.201000
========= master_lag: 0.02 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 14 secs) (at 2016-09-27 13:54:37.449603) =========
========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-09-27 13:54:37.449972) =========
'bash' '-c' u'python archiver_client.py mozharness --repo integration/fx-team --rev b0d4f06aabd64fe182c1f145d70c90681d91028f --destination scripts --debug'
in dir C:\slave\test\. (timeout 1200 secs)
watching logfiles {}
argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev b0d4f06aabd64fe182c1f145d70c90681d91028f --destination scripts --debug']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-106
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-106
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-106
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
2016-09-27 13:54:39,052 truncating revision to first 12 chars
2016-09-27 13:54:39,052 Setting DEBUG logging.
2016-09-27 13:54:39,052 attempt 1/10
2016-09-27 13:54:39,052 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/b0d4f06aabd6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2016-09-27 13:54:39,578 unpacking tar archive at: fx-team-b0d4f06aabd6/testing/mozharness/
2016-09-27 13:54:39,723 skipping symlink on windows: configs/single_locale/linux32.py
program finished with exit code 0
elapsedTime=2.329000
========= master_lag: 0.14 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-09-27 13:54:39.918680) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-27 13:54:39.919174) =========
script_repo_revision: b0d4f06aabd64fe182c1f145d70c90681d91028f
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-27 13:54:39.919646) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-27 13:54:39.920057) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-27 13:54:39.956238) =========
========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 57 secs) (at 2016-09-27 13:54:39.956781) =========
'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Fx-Team' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Fx-Team'
in dir C:\slave\test\. (timeout 3600 secs) (maxTime 7200 secs)
watching logfiles {}
argv: ['c:/mozilla-build/python27/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-106
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-106
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-106
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
13:54:41 INFO - MultiFileLogger online at 20160927 13:54:41 in C:\slave\test
13:54:41 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team
13:54:41 INFO - Dumping config to C:\slave\test\logs\localconfig.json.
13:54:41 INFO - {'append_to_log': False,
13:54:41 INFO - 'base_work_dir': 'C:\\slave\\test',
13:54:41 INFO - 'blob_upload_branch': 'Fx-Team',
13:54:41 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt',
13:54:41 INFO - 'branch': 'Fx-Team',
13:54:41 INFO - 'buildbot_json_path': 'buildprops.json',
13:54:41 INFO - 'config_files': ('talos/windows_config.py',),
13:54:41 INFO - 'default_actions': ('clobber',
13:54:41 INFO - 'read-buildbot-config',
13:54:41 INFO - 'download-and-extract',
13:54:41 INFO - 'populate-webroot',
13:54:41 INFO - 'create-virtualenv',
13:54:41 INFO - 'install',
13:54:41 INFO - 'run-tests'),
13:54:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
13:54:41 INFO - 'download_minidump_stackwalk': True,
13:54:41 INFO - 'download_symbols': 'ondemand',
13:54:41 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python',
13:54:41 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'),
13:54:41 INFO - 'hg': 'c:/mozilla-build/hg/hg',
13:54:41 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python',
13:54:41 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'),
13:54:41 INFO - 'python': 'c:/mozilla-build/python27/python.exe',
13:54:41 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe',
13:54:41 INFO - 'C:/mozilla-build/tooltool.py'),
13:54:41 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe',
13:54:41 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')},
13:54:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
13:54:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
13:54:41 INFO - 'installer_path': 'installer.exe',
13:54:41 INFO - 'log_level': 'info',
13:54:41 INFO - 'log_name': 'talos',
13:54:41 INFO - 'log_to_console': True,
13:54:41 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe',
13:54:41 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe',
13:54:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest',
13:54:41 INFO - 'opt_config_files': (),
13:54:41 INFO - 'pip_index': False,
13:54:41 INFO - 'sps_profile': False,
13:54:41 INFO - 'sps_profile_interval': 0,
13:54:41 INFO - 'suite': 'dromaeojs',
13:54:41 INFO - 'system_bits': '32',
13:54:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
13:54:41 INFO - 'title': 'T-W732-IX-106',
13:54:41 INFO - 'use_talos_json': True,
13:54:41 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'),
13:54:41 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv',
13:54:41 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll',
13:54:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
13:54:41 INFO - 'work_dir': 'build'}
13:54:41 INFO - [mozharness: 2016-09-27 20:54:41.180000Z] Running clobber step.
13:54:41 INFO - Running pre-action listener: _resource_record_pre_action
13:54:41 INFO - Running main action method: clobber
13:54:41 INFO - rmtree: C:\slave\test\build
13:54:41 INFO - Using _rmtree_windows ...
13:54:41 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1
13:56:48 INFO - Running post-action listener: _resource_record_post_action
13:56:48 INFO - [mozharness: 2016-09-27 20:56:48.148000Z] Finished clobber step (success)
13:56:48 INFO - [mozharness: 2016-09-27 20:56:48.148000Z] Running read-buildbot-config step.
13:56:48 INFO - Running pre-action listener: _resource_record_pre_action
13:56:48 INFO - Running main action method: read_buildbot_config
13:56:48 INFO - Using buildbot properties:
13:56:48 INFO - {
13:56:48 INFO - "project": "",
13:56:48 INFO - "product": "firefox",
13:56:48 INFO - "script_repo_revision": "production",
13:56:48 INFO - "builddir": "fx-team_win7_ix_test-dromaeojs",
13:56:48 INFO - "repository": "",
13:56:48 INFO - "buildername": "Windows 7 32-bit fx-team pgo talos dromaeojs",
13:56:48 INFO - "buildid": "20160927093046",
13:56:48 INFO - "pgo_build": "True",
13:56:48 INFO - "basedir": "C:\\slave\\test",
13:56:48 INFO - "buildnumber": 28,
13:56:48 INFO - "slavename": "t-w732-ix-106",
13:56:48 INFO - "master": "http://buildbot-master119.bb.releng.scl3.mozilla.com:8201/",
13:56:48 INFO - "platform": "win7_ix",
13:56:48 INFO - "scheduler": "tests-fx-team-win32-pgo-talos",
13:56:48 INFO - "branch": "fx-team",
13:56:48 INFO - "repo_path": "integration/fx-team",
13:56:48 INFO - "revision": "b0d4f06aabd64fe182c1f145d70c90681d91028f",
13:56:48 INFO - "stage_platform": "win32-pgo",
13:56:48 INFO - "builduid": "adc29abbf3454bf588fd2342cc44952b",
13:56:48 INFO - "slavebuilddir": "test"
13:56:48 INFO - }
13:56:48 INFO - Found installer url https://queue.taskcluster.net/v1/task/edqbP4-VScWWYnLLvUL6LA/artifacts/public/build/firefox-52.0a1.en-US.win32.zip.
13:56:48 INFO - Running post-action listener: _resource_record_post_action
13:56:48 INFO - [mozharness: 2016-09-27 20:56:48.152000Z] Finished read-buildbot-config step (success)
13:56:48 INFO - [mozharness: 2016-09-27 20:56:48.152000Z] Running download-and-extract step.
13:56:48 INFO - Running pre-action listener: _resource_record_pre_action
13:56:48 INFO - Running main action method: download_and_extract
13:56:48 INFO - mkdir: C:\slave\test\build\tests
13:56:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
13:56:48 INFO - trying https://queue.taskcluster.net/v1/task/edqbP4-VScWWYnLLvUL6LA/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json
13:56:48 INFO - Downloading https://queue.taskcluster.net/v1/task/edqbP4-VScWWYnLLvUL6LA/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json to C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json
13:56:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/edqbP4-VScWWYnLLvUL6LA/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json', 'file_name': 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.test_packages.json'}, attempt #1
13:56:49 INFO - Downloaded 1218 bytes.
13:56:49 INFO - Reading from file C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json
13:56:49 INFO - Using the following test package requirements:
13:56:49 INFO - {u'common': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
13:56:49 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
13:56:49 INFO - u'firefox-52.0a1.en-US.win32.cppunittest.tests.zip'],
13:56:49 INFO - u'gtest': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
13:56:49 INFO - u'jittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
13:56:49 INFO - u'jsshell-win32.zip'],
13:56:49 INFO - u'mochitest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
13:56:49 INFO - u'firefox-52.0a1.en-US.win32.mochitest.tests.zip'],
13:56:49 INFO - u'mozbase': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
13:56:49 INFO - u'reftest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
13:56:49 INFO - u'firefox-52.0a1.en-US.win32.reftest.tests.zip'],
13:56:49 INFO - u'talos': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
13:56:49 INFO - u'firefox-52.0a1.en-US.win32.talos.tests.zip'],
13:56:49 INFO - u'web-platform': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
13:56:49 INFO - u'firefox-52.0a1.en-US.win32.web-platform.tests.zip'],
13:56:49 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
13:56:49 INFO - u'firefox-52.0a1.en-US.win32.xpcshell.tests.zip']}
13:56:49 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip'] for test suite category: common
13:56:49 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/edqbP4-VScWWYnLLvUL6LA/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip
13:56:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/edqbP4-VScWWYnLLvUL6LA/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1
13:56:49 INFO - Fetch https://queue.taskcluster.net/v1/task/edqbP4-VScWWYnLLvUL6LA/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory
13:56:49 INFO - Expected file size: 17323029
13:57:18 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip', u'firefox-52.0a1.en-US.win32.talos.tests.zip'] for test suite category: talos
13:57:18 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/edqbP4-VScWWYnLLvUL6LA/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip
13:57:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/edqbP4-VScWWYnLLvUL6LA/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1
13:57:18 INFO - Fetch https://queue.taskcluster.net/v1/task/edqbP4-VScWWYnLLvUL6LA/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory
13:57:19 INFO - Expected file size: 17323029
13:57:29 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/edqbP4-VScWWYnLLvUL6LA/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip
13:57:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/edqbP4-VScWWYnLLvUL6LA/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip'}, attempt #1
13:57:29 INFO - Fetch https://queue.taskcluster.net/v1/task/edqbP4-VScWWYnLLvUL6LA/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip into memory
13:57:30 INFO - Expected file size: 14414062
13:57:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
13:57:40 INFO - trying https://queue.taskcluster.net/v1/task/edqbP4-VScWWYnLLvUL6LA/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
13:57:40 INFO - Downloading https://queue.taskcluster.net/v1/task/edqbP4-VScWWYnLLvUL6LA/artifacts/public/build/firefox-52.0a1.en-US.win32.zip to C:\slave\test\build\firefox-52.0a1.en-US.win32.zip
13:57:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/edqbP4-VScWWYnLLvUL6LA/artifacts/public/build/firefox-52.0a1.en-US.win32.zip', 'file_name': 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.zip'}, attempt #1
13:57:43 INFO - Downloaded 54733798 bytes.
13:57:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/edqbP4-VScWWYnLLvUL6LA/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
13:57:43 INFO - mkdir: C:\slave\test\properties
13:57:43 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url
13:57:43 INFO - Writing to file C:\slave\test\properties\build_url
13:57:43 INFO - Contents:
13:57:43 INFO - build_url:https://queue.taskcluster.net/v1/task/edqbP4-VScWWYnLLvUL6LA/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
13:57:44 INFO - Running post-action listener: _resource_record_post_action
13:57:44 INFO - Running post-action listener: set_extra_try_arguments
13:57:44 INFO - [mozharness: 2016-09-27 20:57:44.204000Z] Finished download-and-extract step (success)
13:57:44 INFO - [mozharness: 2016-09-27 20:57:44.204000Z] Running populate-webroot step.
13:57:44 INFO - Running pre-action listener: _resource_record_pre_action
13:57:44 INFO - Running main action method: populate_webroot
13:57:44 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']},
13:57:44 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']},
13:57:44 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome',
13:57:44 INFO - u'--tppagecycles',
13:57:44 INFO - u'7'],
13:57:44 INFO - u'tests': [u'tsvgm']}},
13:57:44 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'],
13:57:44 INFO - u'tests': [u'tresize', u'tcanvasmark']},
13:57:44 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']},
13:57:44 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'],
13:57:44 INFO - u'tests': [u'dromaeo_css', u'kraken']},
13:57:44 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']},
13:57:44 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
13:57:44 INFO - u'talos_options': [u'--disable-e10s'],
13:57:44 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
13:57:44 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
13:57:44 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
13:57:44 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
13:57:44 INFO - u'talos_options': [u'--disable-e10s'],
13:57:44 INFO - u'tests': [u'damp', u'tps']},
13:57:44 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
13:57:44 INFO - u'tests': [u'damp', u'tps']},
13:57:44 INFO - u'g3': {u'talos_options': [u'--disable-e10s'],
13:57:44 INFO - u'tests': [u'dromaeo_dom']},
13:57:44 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']},
13:57:44 INFO - u'g4': {u'talos_options': [u'--disable-e10s'],
13:57:44 INFO - u'tests': [u'basic_compositor_video']},
13:57:44 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']},
13:57:44 INFO - u'other': {u'talos_options': [u'--disable-e10s'],
13:57:44 INFO - u'tests': [u'a11yr',
13:57:44 INFO - u'ts_paint',
13:57:44 INFO - u'tpaint',
13:57:44 INFO - u'sessionrestore',
13:57:44 INFO - u'sessionrestore_no_auto_restore',
13:57:44 INFO - u'tabpaint']},
13:57:44 INFO - u'other-e10s': {u'tests': [u'a11yr',
13:57:44 INFO - u'ts_paint',
13:57:44 INFO - u'tpaint',
13:57:44 INFO - u'sessionrestore',
13:57:44 INFO - u'sessionrestore_no_auto_restore',
13:57:44 INFO - u'tabpaint']},
13:57:44 INFO - u'other-e10s_l64': {u'tests': [u'a11yr',
13:57:44 INFO - u'ts_paint',
13:57:44 INFO - u'tpaint',
13:57:44 INFO - u'sessionrestore',
13:57:44 INFO - u'sessionrestore_no_auto_restore',
13:57:44 INFO - u'tabpaint']},
13:57:44 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr',
13:57:44 INFO - u'ts_paint',
13:57:44 INFO - u'tpaint',
13:57:44 INFO - u'sessionrestore',
13:57:44 INFO - u'sessionrestore_no_auto_restore',
13:57:44 INFO - u'tabpaint']},
13:57:44 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'],
13:57:44 INFO - u'tests': [u'a11yr',
13:57:44 INFO - u'ts_paint',
13:57:44 INFO - u'tpaint',
13:57:44 INFO - u'sessionrestore',
13:57:44 INFO - u'sessionrestore_no_auto_restore',
13:57:44 INFO - u'tabpaint']},
13:57:44 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'],
13:57:44 INFO - u'tests': [u'a11yr',
13:57:44 INFO - u'ts_paint',
13:57:44 INFO - u'tpaint',
13:57:44 INFO - u'sessionrestore',
13:57:44 INFO - u'sessionrestore_no_auto_restore',
13:57:44 INFO - u'tabpaint']},
13:57:44 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'],
13:57:44 INFO - u'tests': [u'tsvgx',
13:57:44 INFO - u'tsvgr_opacity',
13:57:44 INFO - u'tart',
13:57:44 INFO - u'tscrollx',
13:57:44 INFO - u'cart']},
13:57:44 INFO - u'svgr-e10s': {u'tests': [u'tsvgx',
13:57:44 INFO - u'tsvgr_opacity',
13:57:44 INFO - u'tart',
13:57:44 INFO - u'tscrollx',
13:57:44 INFO - u'cart']},
13:57:44 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
13:57:44 INFO - u'talos_options': [u'--disable-e10s'],
13:57:44 INFO - u'tests': [u'tp5o']},
13:57:44 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
13:57:44 INFO - u'tests': [u'tp5o']},
13:57:44 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
13:57:44 INFO - u'talos_options': [u'--disable-e10s',
13:57:44 INFO - u'--xperf_path',
13:57:44 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
13:57:44 INFO - u'tests': [u'tp5n']},
13:57:44 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
13:57:44 INFO - u'talos_options': [u'--xperf_path',
13:57:44 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
13:57:44 INFO - u'tests': [u'tp5n']}},
13:57:44 INFO - u'talos.zip': {u'path': u'',
13:57:44 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}}
13:57:44 INFO - Running post-action listener: _resource_record_post_action
13:57:44 INFO - [mozharness: 2016-09-27 20:57:44.254000Z] Finished populate-webroot step (success)
13:57:44 INFO - [mozharness: 2016-09-27 20:57:44.254000Z] Running create-virtualenv step.
13:57:44 INFO - Running pre-action listener: _resource_record_pre_action
13:57:44 INFO - Running main action method: create_virtualenv
13:57:44 INFO - Creating virtualenv C:\slave\test\build/venv
13:57:44 INFO - mkdir: C:\slave\test\build\venv\Scripts
13:57:44 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll
13:57:44 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'!
13:57:44 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
13:57:44 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv
13:57:57 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe
13:57:59 INFO - Installing distribute......................................................................................................................................................................................done.
13:58:00 INFO - Return code: 0
13:58:00 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version']
13:58:00 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version
13:58:01 INFO - Reading from file tmpfile_stdout
13:58:01 INFO - Output received:
13:58:01 INFO - pip 0.8.2 from c:\slave\test\build\venv\lib\site-packages\pip-0.8.2-py2.7.egg (python 2.7)
13:58:01 INFO - Using _rmtree_windows ...
13:58:01 INFO - Using _rmtree_windows ...
13:58:01 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv
13:58:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
13:58:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
13:58:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
13:58:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
13:58:01 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 0x01818640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0184FE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01792B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01869E90>, '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 0x0126A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x018D5520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-106', 'USERDOMAIN': 'T-W732-IX-106', '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-106', '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
13:58:01 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
13:58:01 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
13:58:01 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
13:58:01 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
13:58:01 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
13:58:01 INFO - 'COMPUTERNAME': 'T-W732-IX-106',
13:58:01 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
13:58:01 INFO - 'CYGWINBASE': 'C:\\cygwin',
13:58:01 INFO - 'DCLOCATION': 'SCL3',
13:58:01 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
13:58:01 INFO - 'FP_NO_HOST_CHECK': 'NO',
13:58:01 INFO - 'HOMEDRIVE': 'C:',
13:58:01 INFO - 'HOMEPATH': '\\Users\\cltbld',
13:58:01 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
13:58:01 INFO - 'KTS_VERSION': '1.19c',
13:58:01 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
13:58:01 INFO - 'LOGONSERVER': '\\\\T-W732-IX-106',
13:58:01 INFO - 'MONDIR': 'C:\\Monitor_config\\',
13:58:01 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
13:58:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:58:01 INFO - 'MOZ_NO_REMOTE': '1',
13:58:01 INFO - 'NO_EM_RESTART': '1',
13:58:01 INFO - 'NUMBER_OF_PROCESSORS': '8',
13:58:01 INFO - 'OS': 'Windows_NT',
13:58:01 INFO - 'OURDRIVE': 'C:',
13:58:01 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
13:58:01 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
13:58:01 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
13:58:01 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
13:58:01 INFO - 'PROCESSOR_LEVEL': '6',
13:58:01 INFO - 'PROCESSOR_REVISION': '1e05',
13:58:01 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
13:58:01 INFO - 'PROGRAMFILES': 'C:\\Program Files',
13:58:01 INFO - 'PROMPT': '$P$G',
13:58:01 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
13:58:01 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
13:58:01 INFO - 'PUBLIC': 'C:\\Users\\Public',
13:58:01 INFO - 'PWD': 'C:\\slave\\test',
13:58:01 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
13:58:01 INFO - 'SLAVEDIR': 'C:\\slave\\',
13:58:01 INFO - 'SYSTEMDRIVE': 'C:',
13:58:01 INFO - 'SYSTEMROOT': 'C:\\windows',
13:58:01 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
13:58:01 INFO - 'TEST1': 'testie',
13:58:01 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
13:58:01 INFO - 'USERDOMAIN': 'T-W732-IX-106',
13:58:01 INFO - 'USERNAME': 'cltbld',
13:58:01 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
13:58:01 INFO - 'WINDIR': 'C:\\windows',
13:58:01 INFO - 'WINDOWS_TRACING_FLAGS': '3',
13:58:01 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
13:58:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
13:58:02 INFO - Ignoring indexes: http://pypi.python.org/simple/
13:58:02 INFO - Downloading/unpacking pip>=1.5
13:58:06 INFO - Running setup.py egg_info for package pip
13:58:06 INFO - warning: no files found matching 'pip\cacert.pem'
13:58:06 INFO - warning: no files found matching '*.html' under directory 'docs'
13:58:06 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
13:58:06 INFO - no previously-included directories found matching 'docs\_build\_sources'
13:58:06 INFO - Installing collected packages: pip
13:58:06 INFO - Found existing installation: pip 0.8.2
13:58:06 INFO - Uninstalling pip:
13:58:06 INFO - Successfully uninstalled pip
13:58:06 INFO - Running setup.py install for pip
13:58:06 INFO - warning: no files found matching 'pip\cacert.pem'
13:58:06 INFO - warning: no files found matching '*.html' under directory 'docs'
13:58:06 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
13:58:06 INFO - no previously-included directories found matching 'docs\_build\_sources'
13:58:06 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts
13:58:06 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts
13:58:06 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts
13:58:06 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts
13:58:06 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts
13:58:06 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts
13:58:06 INFO - Successfully installed pip
13:58:06 INFO - Cleaning up...
13:58:06 INFO - Return code: 0
13:58:06 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv
13:58:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
13:58:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
13:58:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
13:58:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
13:58:06 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 0x01818640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0184FE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01792B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01869E90>, '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 0x0126A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x018D5520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-106', 'USERDOMAIN': 'T-W732-IX-106', '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-106', '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
13:58:06 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
13:58:06 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
13:58:06 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
13:58:06 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
13:58:06 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
13:58:06 INFO - 'COMPUTERNAME': 'T-W732-IX-106',
13:58:06 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
13:58:06 INFO - 'CYGWINBASE': 'C:\\cygwin',
13:58:06 INFO - 'DCLOCATION': 'SCL3',
13:58:06 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
13:58:06 INFO - 'FP_NO_HOST_CHECK': 'NO',
13:58:06 INFO - 'HOMEDRIVE': 'C:',
13:58:06 INFO - 'HOMEPATH': '\\Users\\cltbld',
13:58:06 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
13:58:06 INFO - 'KTS_VERSION': '1.19c',
13:58:06 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
13:58:06 INFO - 'LOGONSERVER': '\\\\T-W732-IX-106',
13:58:06 INFO - 'MONDIR': 'C:\\Monitor_config\\',
13:58:06 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
13:58:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:58:06 INFO - 'MOZ_NO_REMOTE': '1',
13:58:06 INFO - 'NO_EM_RESTART': '1',
13:58:06 INFO - 'NUMBER_OF_PROCESSORS': '8',
13:58:06 INFO - 'OS': 'Windows_NT',
13:58:06 INFO - 'OURDRIVE': 'C:',
13:58:06 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;',
13:58:06 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
13:58:06 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
13:58:06 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
13:58:06 INFO - 'PROCESSOR_LEVEL': '6',
13:58:06 INFO - 'PROCESSOR_REVISION': '1e05',
13:58:06 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
13:58:06 INFO - 'PROGRAMFILES': 'C:\\Program Files',
13:58:06 INFO - 'PROMPT': '$P$G',
13:58:06 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
13:58:06 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
13:58:06 INFO - 'PUBLIC': 'C:\\Users\\Public',
13:58:06 INFO - 'PWD': 'C:\\slave\\test',
13:58:06 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
13:58:06 INFO - 'SLAVEDIR': 'C:\\slave\\',
13:58:06 INFO - 'SYSTEMDRIVE': 'C:',
13:58:06 INFO - 'SYSTEMROOT': 'C:\\windows',
13:58:06 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
13:58:06 INFO - 'TEST1': 'testie',
13:58:06 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
13:58:06 INFO - 'USERDOMAIN': 'T-W732-IX-106',
13:58:06 INFO - 'USERNAME': 'cltbld',
13:58:06 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
13:58:06 INFO - 'WINDIR': 'C:\\windows',
13:58:06 INFO - 'WINDOWS_TRACING_FLAGS': '3',
13:58:06 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
13:58:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
13:58:08 INFO - Ignoring indexes: https://pypi.python.org/simple/
13:58:08 INFO - Downloading/unpacking psutil>=3.1.1
13:58:08 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
13:58:08 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
13:58:09 INFO - Installing collected packages: psutil
13:58:09 INFO - Successfully installed psutil
13:58:09 INFO - Cleaning up...
13:58:09 INFO - Return code: 0
13:58:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv
13:58:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
13:58:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
13:58:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
13:58:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
13:58:09 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01818640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0184FE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01792B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01869E90>, '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 0x0126A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x018D5520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-106', 'USERDOMAIN': 'T-W732-IX-106', '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-106', '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
13:58:09 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in C:\slave\test\build
13:58:09 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3
13:58:09 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
13:58:09 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
13:58:09 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
13:58:09 INFO - 'COMPUTERNAME': 'T-W732-IX-106',
13:58:09 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
13:58:09 INFO - 'CYGWINBASE': 'C:\\cygwin',
13:58:09 INFO - 'DCLOCATION': 'SCL3',
13:58:09 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
13:58:09 INFO - 'FP_NO_HOST_CHECK': 'NO',
13:58:09 INFO - 'HOMEDRIVE': 'C:',
13:58:09 INFO - 'HOMEPATH': '\\Users\\cltbld',
13:58:09 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
13:58:09 INFO - 'KTS_VERSION': '1.19c',
13:58:09 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
13:58:09 INFO - 'LOGONSERVER': '\\\\T-W732-IX-106',
13:58:09 INFO - 'MONDIR': 'C:\\Monitor_config\\',
13:58:09 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
13:58:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:58:09 INFO - 'MOZ_NO_REMOTE': '1',
13:58:09 INFO - 'NO_EM_RESTART': '1',
13:58:09 INFO - 'NUMBER_OF_PROCESSORS': '8',
13:58:09 INFO - 'OS': 'Windows_NT',
13:58:09 INFO - 'OURDRIVE': 'C:',
13:58:09 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
13:58:09 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
13:58:09 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
13:58:09 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
13:58:09 INFO - 'PROCESSOR_LEVEL': '6',
13:58:09 INFO - 'PROCESSOR_REVISION': '1e05',
13:58:09 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
13:58:09 INFO - 'PROGRAMFILES': 'C:\\Program Files',
13:58:09 INFO - 'PROMPT': '$P$G',
13:58:09 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
13:58:09 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
13:58:09 INFO - 'PUBLIC': 'C:\\Users\\Public',
13:58:09 INFO - 'PWD': 'C:\\slave\\test',
13:58:09 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
13:58:09 INFO - 'SLAVEDIR': 'C:\\slave\\',
13:58:09 INFO - 'SYSTEMDRIVE': 'C:',
13:58:09 INFO - 'SYSTEMROOT': 'C:\\windows',
13:58:09 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
13:58:09 INFO - 'TEST1': 'testie',
13:58:09 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
13:58:09 INFO - 'USERDOMAIN': 'T-W732-IX-106',
13:58:09 INFO - 'USERNAME': 'cltbld',
13:58:09 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
13:58:09 INFO - 'WINDIR': 'C:\\windows',
13:58:09 INFO - 'WINDOWS_TRACING_FLAGS': '3',
13:58:09 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
13:58:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
13:58:09 INFO - Ignoring indexes: https://pypi.python.org/simple/
13:58:09 INFO - Downloading/unpacking mozsystemmonitor==0.3
13:58:09 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
13:58:09 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
13:58:10 INFO - Downloading mozsystemmonitor-0.3.tar.gz
13:58:10 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor
13:58:11 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)
13:58:11 INFO - Installing collected packages: mozsystemmonitor
13:58:11 INFO - Running setup.py install for mozsystemmonitor
13:58:11 INFO - Successfully installed mozsystemmonitor
13:58:11 INFO - Cleaning up...
13:58:11 INFO - Return code: 0
13:58:11 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv
13:58:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
13:58:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
13:58:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
13:58:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
13:58:11 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x01818640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0184FE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01792B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01869E90>, '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 0x0126A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x018D5520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-106', 'USERDOMAIN': 'T-W732-IX-106', '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-106', '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
13:58:11 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in C:\slave\test\build
13:58:11 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1
13:58:11 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
13:58:11 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
13:58:11 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
13:58:11 INFO - 'COMPUTERNAME': 'T-W732-IX-106',
13:58:11 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
13:58:11 INFO - 'CYGWINBASE': 'C:\\cygwin',
13:58:11 INFO - 'DCLOCATION': 'SCL3',
13:58:11 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
13:58:11 INFO - 'FP_NO_HOST_CHECK': 'NO',
13:58:11 INFO - 'HOMEDRIVE': 'C:',
13:58:11 INFO - 'HOMEPATH': '\\Users\\cltbld',
13:58:11 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
13:58:11 INFO - 'KTS_VERSION': '1.19c',
13:58:11 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
13:58:11 INFO - 'LOGONSERVER': '\\\\T-W732-IX-106',
13:58:11 INFO - 'MONDIR': 'C:\\Monitor_config\\',
13:58:11 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
13:58:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:58:11 INFO - 'MOZ_NO_REMOTE': '1',
13:58:11 INFO - 'NO_EM_RESTART': '1',
13:58:11 INFO - 'NUMBER_OF_PROCESSORS': '8',
13:58:11 INFO - 'OS': 'Windows_NT',
13:58:11 INFO - 'OURDRIVE': 'C:',
13:58:11 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
13:58:11 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
13:58:11 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
13:58:11 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
13:58:11 INFO - 'PROCESSOR_LEVEL': '6',
13:58:11 INFO - 'PROCESSOR_REVISION': '1e05',
13:58:11 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
13:58:11 INFO - 'PROGRAMFILES': 'C:\\Program Files',
13:58:11 INFO - 'PROMPT': '$P$G',
13:58:11 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
13:58:11 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
13:58:11 INFO - 'PUBLIC': 'C:\\Users\\Public',
13:58:11 INFO - 'PWD': 'C:\\slave\\test',
13:58:11 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
13:58:11 INFO - 'SLAVEDIR': 'C:\\slave\\',
13:58:11 INFO - 'SYSTEMDRIVE': 'C:',
13:58:11 INFO - 'SYSTEMROOT': 'C:\\windows',
13:58:11 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
13:58:11 INFO - 'TEST1': 'testie',
13:58:11 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
13:58:11 INFO - 'USERDOMAIN': 'T-W732-IX-106',
13:58:11 INFO - 'USERNAME': 'cltbld',
13:58:11 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
13:58:11 INFO - 'WINDIR': 'C:\\windows',
13:58:11 INFO - 'WINDOWS_TRACING_FLAGS': '3',
13:58:11 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
13:58:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
13:58:11 INFO - Ignoring indexes: https://pypi.python.org/simple/
13:58:11 INFO - Downloading/unpacking jsonschema==2.5.1
13:58:11 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
13:58:11 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
13:58:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl
13:58:13 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1)
13:58:13 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
13:58:13 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
13:58:13 INFO - Downloading functools32-3.2.3-2.tar.gz
13:58:13 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32
13:58:13 INFO - warning: no files found matching '*.txt'
13:58:13 INFO - no previously-included directories found matching 'build'
13:58:13 INFO - no previously-included directories found matching 'dist'
13:58:13 INFO - no previously-included directories found matching '.git*'
13:58:13 INFO - Installing collected packages: jsonschema, functools32
13:58:13 INFO - Running setup.py install for functools32
13:58:13 INFO - warning: no files found matching '*.txt'
13:58:13 INFO - no previously-included directories found matching 'build'
13:58:13 INFO - no previously-included directories found matching 'dist'
13:58:13 INFO - no previously-included directories found matching '.git*'
13:58:14 INFO - Successfully installed jsonschema functools32
13:58:14 INFO - Cleaning up...
13:58:14 INFO - Return code: 0
13:58:14 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv
13:58: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')]}
13:58:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
13:58: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')]}
13:58:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
13:58:14 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 0x01818640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0184FE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01792B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01869E90>, '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 0x0126A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x018D5520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-106', 'USERDOMAIN': 'T-W732-IX-106', '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-106', '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
13:58:14 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
13:58:14 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
13:58:14 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
13:58:14 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
13:58:14 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
13:58:14 INFO - 'COMPUTERNAME': 'T-W732-IX-106',
13:58:14 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
13:58:14 INFO - 'CYGWINBASE': 'C:\\cygwin',
13:58:14 INFO - 'DCLOCATION': 'SCL3',
13:58:14 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
13:58:14 INFO - 'FP_NO_HOST_CHECK': 'NO',
13:58:14 INFO - 'HOMEDRIVE': 'C:',
13:58:14 INFO - 'HOMEPATH': '\\Users\\cltbld',
13:58:14 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
13:58:14 INFO - 'KTS_VERSION': '1.19c',
13:58:14 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
13:58:14 INFO - 'LOGONSERVER': '\\\\T-W732-IX-106',
13:58:14 INFO - 'MONDIR': 'C:\\Monitor_config\\',
13:58:14 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
13:58:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:58:14 INFO - 'MOZ_NO_REMOTE': '1',
13:58:14 INFO - 'NO_EM_RESTART': '1',
13:58:14 INFO - 'NUMBER_OF_PROCESSORS': '8',
13:58:14 INFO - 'OS': 'Windows_NT',
13:58:14 INFO - 'OURDRIVE': 'C:',
13:58: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;',
13:58:14 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
13:58:14 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
13:58:14 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
13:58:14 INFO - 'PROCESSOR_LEVEL': '6',
13:58:14 INFO - 'PROCESSOR_REVISION': '1e05',
13:58:14 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
13:58:14 INFO - 'PROGRAMFILES': 'C:\\Program Files',
13:58:14 INFO - 'PROMPT': '$P$G',
13:58:14 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
13:58:14 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
13:58:14 INFO - 'PUBLIC': 'C:\\Users\\Public',
13:58:14 INFO - 'PWD': 'C:\\slave\\test',
13:58:14 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
13:58:14 INFO - 'SLAVEDIR': 'C:\\slave\\',
13:58:14 INFO - 'SYSTEMDRIVE': 'C:',
13:58:14 INFO - 'SYSTEMROOT': 'C:\\windows',
13:58:14 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
13:58:14 INFO - 'TEST1': 'testie',
13:58:14 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
13:58:14 INFO - 'USERDOMAIN': 'T-W732-IX-106',
13:58:14 INFO - 'USERNAME': 'cltbld',
13:58:14 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
13:58:14 INFO - 'WINDIR': 'C:\\windows',
13:58:14 INFO - 'WINDOWS_TRACING_FLAGS': '3',
13:58:14 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
13:58:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
13:58:14 INFO - Ignoring indexes: https://pypi.python.org/simple/
13:58:14 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages
13:58:14 INFO - Cleaning up...
13:58:14 INFO - Return code: 0
13:58:14 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv
13:58: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')]}
13:58:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
13:58: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')]}
13:58:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
13:58:14 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 0x01818640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0184FE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01792B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01869E90>, '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 0x0126A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x018D5520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-106', 'USERDOMAIN': 'T-W732-IX-106', '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-106', '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
13:58:14 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
13:58:14 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
13:58:14 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
13:58:14 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
13:58:14 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
13:58:14 INFO - 'COMPUTERNAME': 'T-W732-IX-106',
13:58:14 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
13:58:14 INFO - 'CYGWINBASE': 'C:\\cygwin',
13:58:14 INFO - 'DCLOCATION': 'SCL3',
13:58:14 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
13:58:14 INFO - 'FP_NO_HOST_CHECK': 'NO',
13:58:14 INFO - 'HOMEDRIVE': 'C:',
13:58:14 INFO - 'HOMEPATH': '\\Users\\cltbld',
13:58:14 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
13:58:14 INFO - 'KTS_VERSION': '1.19c',
13:58:14 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
13:58:14 INFO - 'LOGONSERVER': '\\\\T-W732-IX-106',
13:58:14 INFO - 'MONDIR': 'C:\\Monitor_config\\',
13:58:14 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
13:58:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:58:14 INFO - 'MOZ_NO_REMOTE': '1',
13:58:14 INFO - 'NO_EM_RESTART': '1',
13:58:14 INFO - 'NUMBER_OF_PROCESSORS': '8',
13:58:14 INFO - 'OS': 'Windows_NT',
13:58:14 INFO - 'OURDRIVE': 'C:',
13:58: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;',
13:58:14 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
13:58:14 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
13:58:14 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
13:58:14 INFO - 'PROCESSOR_LEVEL': '6',
13:58:14 INFO - 'PROCESSOR_REVISION': '1e05',
13:58:14 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
13:58:14 INFO - 'PROGRAMFILES': 'C:\\Program Files',
13:58:14 INFO - 'PROMPT': '$P$G',
13:58:14 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
13:58:14 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
13:58:14 INFO - 'PUBLIC': 'C:\\Users\\Public',
13:58:14 INFO - 'PWD': 'C:\\slave\\test',
13:58:14 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
13:58:14 INFO - 'SLAVEDIR': 'C:\\slave\\',
13:58:14 INFO - 'SYSTEMDRIVE': 'C:',
13:58:14 INFO - 'SYSTEMROOT': 'C:\\windows',
13:58:14 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
13:58:14 INFO - 'TEST1': 'testie',
13:58:14 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
13:58:14 INFO - 'USERDOMAIN': 'T-W732-IX-106',
13:58:14 INFO - 'USERNAME': 'cltbld',
13:58:14 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
13:58:14 INFO - 'WINDIR': 'C:\\windows',
13:58:14 INFO - 'WINDOWS_TRACING_FLAGS': '3',
13:58:14 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
13:58:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
13:58:15 INFO - Ignoring indexes: https://pypi.python.org/simple/
13:58:15 INFO - Downloading/unpacking blobuploader==1.2.4
13:58:15 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
13:58:15 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
13:58:16 INFO - Downloading blobuploader-1.2.4.tar.gz
13:58:16 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader
13:58:16 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4)
13:58:16 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
13:58:16 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
13:58:16 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests
13:58:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4)
13:58:16 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
13:58:16 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
13:58:17 INFO - Downloading docopt-0.6.1.tar.gz
13:58:17 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt
13:58:17 INFO - Installing collected packages: blobuploader, requests, docopt
13:58:17 INFO - Running setup.py install for blobuploader
13:58:17 INFO - Running setup.py install for requests
13:58:18 INFO - Running setup.py install for docopt
13:58:18 INFO - Successfully installed blobuploader requests docopt
13:58:18 INFO - Cleaning up...
13:58:19 INFO - Return code: 0
13:58:19 INFO - Installing None into virtualenv C:\slave\test\build/venv
13:58:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
13:58:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
13:58:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
13:58:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
13:58:19 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 0x01818640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0184FE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01792B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01869E90>, '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 0x0126A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x018D5520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-106', 'USERDOMAIN': 'T-W732-IX-106', '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-106', '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
13:58:19 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
13:58:19 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
13:58:19 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
13:58:19 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
13:58:19 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
13:58:19 INFO - 'COMPUTERNAME': 'T-W732-IX-106',
13:58:19 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
13:58:19 INFO - 'CYGWINBASE': 'C:\\cygwin',
13:58:19 INFO - 'DCLOCATION': 'SCL3',
13:58:19 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
13:58:19 INFO - 'FP_NO_HOST_CHECK': 'NO',
13:58:19 INFO - 'HOMEDRIVE': 'C:',
13:58:19 INFO - 'HOMEPATH': '\\Users\\cltbld',
13:58:19 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
13:58:19 INFO - 'KTS_VERSION': '1.19c',
13:58:19 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
13:58:19 INFO - 'LOGONSERVER': '\\\\T-W732-IX-106',
13:58:19 INFO - 'MONDIR': 'C:\\Monitor_config\\',
13:58:19 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
13:58:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:58:19 INFO - 'MOZ_NO_REMOTE': '1',
13:58:19 INFO - 'NO_EM_RESTART': '1',
13:58:19 INFO - 'NUMBER_OF_PROCESSORS': '8',
13:58:19 INFO - 'OS': 'Windows_NT',
13:58:19 INFO - 'OURDRIVE': 'C:',
13:58:19 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
13:58:19 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
13:58:19 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
13:58:19 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
13:58:19 INFO - 'PROCESSOR_LEVEL': '6',
13:58:19 INFO - 'PROCESSOR_REVISION': '1e05',
13:58:19 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
13:58:19 INFO - 'PROGRAMFILES': 'C:\\Program Files',
13:58:19 INFO - 'PROMPT': '$P$G',
13:58:19 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
13:58:19 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
13:58:19 INFO - 'PUBLIC': 'C:\\Users\\Public',
13:58:19 INFO - 'PWD': 'C:\\slave\\test',
13:58:19 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
13:58:19 INFO - 'SLAVEDIR': 'C:\\slave\\',
13:58:19 INFO - 'SYSTEMDRIVE': 'C:',
13:58:19 INFO - 'SYSTEMROOT': 'C:\\windows',
13:58:19 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
13:58:19 INFO - 'TEST1': 'testie',
13:58:19 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
13:58:19 INFO - 'USERDOMAIN': 'T-W732-IX-106',
13:58:19 INFO - 'USERNAME': 'cltbld',
13:58:19 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
13:58:19 INFO - 'WINDIR': 'C:\\windows',
13:58:19 INFO - 'WINDOWS_TRACING_FLAGS': '3',
13:58:19 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
13:58:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
13:58:20 INFO - Ignoring indexes: https://pypi.python.org/simple/
13:58:20 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
13:58:20 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ukf_w7-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
13:58:20 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
13:58:20 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-an2dlf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
13:58:20 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
13:58:20 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hbyeoi-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
13:58:20 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
13:58:20 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xayzyt-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
13:58:20 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
13:58:20 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ejfli_-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
13:58:20 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
13:58:20 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-y86kiq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
13:58:20 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
13:58:20 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dyrkgs-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
13:58:21 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
13:58:21 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-esxhzz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
13:58:21 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
13:58:21 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-huvwrx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
13:58:21 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
13:58:21 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-errhsh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
13:58:21 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
13:58:21 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ylb7js-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
13:58:21 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
13:58:21 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zklz7e-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
13:58:21 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
13:58:21 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dknjok-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
13:58:21 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
13:58:21 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-270stz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
13:58:22 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
13:58:22 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-i9oy4j-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
13:58:22 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
13:58:22 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-2j5aaa-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
13:58:22 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
13:58:22 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-awmmhl-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
13:58:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
13:58:22 INFO - Running setup.py install for manifestparser
13:58:22 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts
13:58:22 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts
13:58:23 INFO - Running setup.py install for mozcrash
13:58:23 INFO - Running setup.py install for mozdebug
13:58:23 INFO - Running setup.py install for mozdevice
13:58:24 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts
13:58:24 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts
13:58:24 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts
13:58:24 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts
13:58:24 INFO - Running setup.py install for mozfile
13:58:24 INFO - Running setup.py install for mozhttpd
13:58:25 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts
13:58:25 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts
13:58:25 INFO - Running setup.py install for mozinfo
13:58:25 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts
13:58:25 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts
13:58:25 INFO - Running setup.py install for mozInstall
13:58:25 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts
13:58:25 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts
13:58:25 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts
13:58:25 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts
13:58:25 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts
13:58:25 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts
13:58:25 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts
13:58:25 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts
13:58:25 INFO - Running setup.py install for mozleak
13:58:26 INFO - Running setup.py install for mozlog
13:58:26 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts
13:58:26 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts
13:58:27 INFO - Running setup.py install for moznetwork
13:58:27 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts
13:58:27 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts
13:58:27 INFO - Running setup.py install for mozprocess
13:58:28 INFO - Running setup.py install for mozprofile
13:58:28 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts
13:58:28 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts
13:58:28 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts
13:58:28 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts
13:58:28 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts
13:58:28 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts
13:58:28 INFO - Running setup.py install for mozrunner
13:58:29 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts
13:58:29 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts
13:58:29 INFO - Running setup.py install for mozscreenshot
13:58:29 INFO - Running setup.py install for moztest
13:58:30 INFO - Running setup.py install for mozversion
13:58:30 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts
13:58:30 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts
13:58:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion
13:58:30 INFO - Cleaning up...
13:58:30 INFO - Return code: 0
13:58:30 INFO - Installing None into virtualenv C:\slave\test\build/venv
13:58: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')]}
13:58:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
13:58: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')]}
13:58:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
13:58:30 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 0x01818640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0184FE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01792B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01869E90>, '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 0x0126A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x018D5520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-106', 'USERDOMAIN': 'T-W732-IX-106', '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-106', '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
13:58:30 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
13:58:30 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
13:58:30 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
13:58:30 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
13:58:30 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
13:58:30 INFO - 'COMPUTERNAME': 'T-W732-IX-106',
13:58:30 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
13:58:30 INFO - 'CYGWINBASE': 'C:\\cygwin',
13:58:30 INFO - 'DCLOCATION': 'SCL3',
13:58:30 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
13:58:30 INFO - 'FP_NO_HOST_CHECK': 'NO',
13:58:30 INFO - 'HOMEDRIVE': 'C:',
13:58:30 INFO - 'HOMEPATH': '\\Users\\cltbld',
13:58:30 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
13:58:30 INFO - 'KTS_VERSION': '1.19c',
13:58:30 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
13:58:30 INFO - 'LOGONSERVER': '\\\\T-W732-IX-106',
13:58:30 INFO - 'MONDIR': 'C:\\Monitor_config\\',
13:58:30 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
13:58:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:58:30 INFO - 'MOZ_NO_REMOTE': '1',
13:58:30 INFO - 'NO_EM_RESTART': '1',
13:58:30 INFO - 'NUMBER_OF_PROCESSORS': '8',
13:58:30 INFO - 'OS': 'Windows_NT',
13:58:30 INFO - 'OURDRIVE': 'C:',
13:58:30 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
13:58:30 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
13:58:30 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
13:58:30 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
13:58:30 INFO - 'PROCESSOR_LEVEL': '6',
13:58:30 INFO - 'PROCESSOR_REVISION': '1e05',
13:58:30 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
13:58:30 INFO - 'PROGRAMFILES': 'C:\\Program Files',
13:58:30 INFO - 'PROMPT': '$P$G',
13:58:30 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
13:58:30 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
13:58:30 INFO - 'PUBLIC': 'C:\\Users\\Public',
13:58:30 INFO - 'PWD': 'C:\\slave\\test',
13:58:30 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
13:58:30 INFO - 'SLAVEDIR': 'C:\\slave\\',
13:58:30 INFO - 'SYSTEMDRIVE': 'C:',
13:58:30 INFO - 'SYSTEMROOT': 'C:\\windows',
13:58:30 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
13:58:30 INFO - 'TEST1': 'testie',
13:58:30 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
13:58:30 INFO - 'USERDOMAIN': 'T-W732-IX-106',
13:58:30 INFO - 'USERNAME': 'cltbld',
13:58:30 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
13:58:30 INFO - 'WINDIR': 'C:\\windows',
13:58:30 INFO - 'WINDOWS_TRACING_FLAGS': '3',
13:58:30 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
13:58:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
13:58:30 INFO - Ignoring indexes: https://pypi.python.org/simple/
13:58:30 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
13:58:31 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-yedu63-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
13:58:31 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))
13:58:31 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
13:58:31 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-a05mcg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
13:58:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///C:/slave/test/build/tests/mozbase/mozcrash in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2))
13:58:31 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
13:58:31 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-6pj4yf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
13:58:31 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))
13:58:31 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
13:58:31 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hbsgyd-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
13:58:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///C:/slave/test/build/tests/mozbase/mozdevice in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4))
13:58:31 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
13:58:31 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xaippe-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
13:58:31 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))
13:58:31 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
13:58:31 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-syknnw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
13:58:31 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))
13:58:31 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
13:58:31 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dtbvl5-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
13:58:31 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))
13:58:31 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
13:58:31 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xfaqh7-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
13:58:32 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))
13:58:32 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
13:58:32 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-8vasol-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
13:58:32 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))
13:58:32 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
13:58:32 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-b1oimn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
13:58:32 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))
13:58:32 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
13:58:32 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lq2rog-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
13:58:32 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))
13:58:32 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
13:58:32 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cfqoa5-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
13:58:32 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))
13:58:32 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
13:58:32 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-biztbg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
13:58:32 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))
13:58:32 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
13:58:32 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fyt21r-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
13:58:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///C:/slave/test/build/tests/mozbase/mozrunner in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 14))
13:58:33 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
13:58:33 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-7ojtox-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
13:58:33 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))
13:58:33 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
13:58:33 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-decamj-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
13:58:33 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))
13:58:33 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
13:58:33 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-nmmjue-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
13:58:33 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))
13:58:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==0.17->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2))
13:58:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==0.17->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2))
13:58:33 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))
13:58:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.48->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4))
13:58:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.48->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4))
13:58:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10))
13:58:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
13:58:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
13:58:34 INFO - Downloading blessings-1.6.tar.gz
13:58:34 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings
13:58:34 INFO - Installing collected packages: blessings
13:58:34 INFO - Running setup.py install for blessings
13:58:35 INFO - Successfully installed blessings
13:58:35 INFO - Cleaning up...
13:58:37 INFO - Return code: 0
13:58:37 INFO - Done creating virtualenv C:\slave\test\build/venv.
13:58:37 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
13:58:37 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
13:58:38 INFO - Reading from file tmpfile_stdout
13:58:38 INFO - Using _rmtree_windows ...
13:58:38 INFO - Using _rmtree_windows ...
13:58:38 INFO - Current package versions:
13:58:38 INFO - blessings == 1.6
13:58:38 INFO - blobuploader == 1.2.4
13:58:38 INFO - docopt == 0.6.1
13:58:38 INFO - functools32 == 3.2.3-2
13:58:38 INFO - jsonschema == 2.5.1
13:58:38 INFO - manifestparser == 1.1
13:58:38 INFO - mozInstall == 1.12
13:58:38 INFO - mozcrash == 0.17
13:58:38 INFO - mozdebug == 0.1
13:58:38 INFO - mozdevice == 0.48
13:58:38 INFO - mozfile == 1.2
13:58:38 INFO - mozhttpd == 0.7
13:58:38 INFO - mozinfo == 0.9
13:58:38 INFO - mozleak == 0.1
13:58:38 INFO - mozlog == 3.4
13:58:38 INFO - moznetwork == 0.27
13:58:38 INFO - mozprocess == 0.23
13:58:38 INFO - mozprofile == 0.28
13:58:38 INFO - mozrunner == 6.12
13:58:38 INFO - mozscreenshot == 0.1
13:58:38 INFO - mozsystemmonitor == 0.3
13:58:38 INFO - moztest == 0.7
13:58:38 INFO - mozversion == 1.4
13:58:38 INFO - psutil == 3.1.1
13:58:38 INFO - requests == 1.2.3
13:58:38 INFO - Installing None into virtualenv C:\slave\test\build/venv
13:58:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
13:58:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
13:58:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
13:58:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
13:58:38 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 0x01818640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0184FE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01792B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01869E90>, '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 0x0126A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x018D5520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-106', 'USERDOMAIN': 'T-W732-IX-106', '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-106', '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
13:58:38 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
13:58:38 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
13:58:38 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
13:58:38 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
13:58:38 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
13:58:38 INFO - 'COMPUTERNAME': 'T-W732-IX-106',
13:58:38 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
13:58:38 INFO - 'CYGWINBASE': 'C:\\cygwin',
13:58:38 INFO - 'DCLOCATION': 'SCL3',
13:58:38 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
13:58:38 INFO - 'FP_NO_HOST_CHECK': 'NO',
13:58:38 INFO - 'HOMEDRIVE': 'C:',
13:58:38 INFO - 'HOMEPATH': '\\Users\\cltbld',
13:58:38 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
13:58:38 INFO - 'KTS_VERSION': '1.19c',
13:58:38 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
13:58:38 INFO - 'LOGONSERVER': '\\\\T-W732-IX-106',
13:58:38 INFO - 'MONDIR': 'C:\\Monitor_config\\',
13:58:38 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
13:58:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:58:38 INFO - 'MOZ_NO_REMOTE': '1',
13:58:38 INFO - 'NO_EM_RESTART': '1',
13:58:38 INFO - 'NUMBER_OF_PROCESSORS': '8',
13:58:38 INFO - 'OS': 'Windows_NT',
13:58:38 INFO - 'OURDRIVE': 'C:',
13:58:38 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
13:58:38 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
13:58:38 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
13:58:38 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
13:58:38 INFO - 'PROCESSOR_LEVEL': '6',
13:58:38 INFO - 'PROCESSOR_REVISION': '1e05',
13:58:38 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
13:58:38 INFO - 'PROGRAMFILES': 'C:\\Program Files',
13:58:38 INFO - 'PROMPT': '$P$G',
13:58:38 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
13:58:38 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
13:58:38 INFO - 'PUBLIC': 'C:\\Users\\Public',
13:58:38 INFO - 'PWD': 'C:\\slave\\test',
13:58:38 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
13:58:38 INFO - 'SLAVEDIR': 'C:\\slave\\',
13:58:38 INFO - 'SYSTEMDRIVE': 'C:',
13:58:38 INFO - 'SYSTEMROOT': 'C:\\windows',
13:58:38 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
13:58:38 INFO - 'TEST1': 'testie',
13:58:38 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
13:58:38 INFO - 'USERDOMAIN': 'T-W732-IX-106',
13:58:38 INFO - 'USERNAME': 'cltbld',
13:58:38 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
13:58:38 INFO - 'WINDIR': 'C:\\windows',
13:58:38 INFO - 'WINDOWS_TRACING_FLAGS': '3',
13:58:38 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
13:58:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
13:58:38 INFO - Ignoring indexes: https://pypi.python.org/simple/
13:58:38 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))
13:58:38 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))
13:58:38 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))
13:58:38 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))
13:58:38 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))
13:58:38 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))
13:58:38 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))
13:58:38 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))
13:58:38 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))
13:58:38 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))
13:58:38 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))
13:58:38 INFO - Cleaning up...
13:58:38 INFO - Return code: 0
13:58:38 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv
13:58:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
13:58:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
13:58:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
13:58:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
13:58:38 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01818640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0184FE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01792B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01869E90>, '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 0x0126A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x018D5520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-106', 'USERDOMAIN': 'T-W732-IX-106', '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-106', '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
13:58:38 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in C:\slave\test\build
13:58:38 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema
13:58:38 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
13:58:38 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
13:58:38 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
13:58:38 INFO - 'COMPUTERNAME': 'T-W732-IX-106',
13:58:38 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
13:58:38 INFO - 'CYGWINBASE': 'C:\\cygwin',
13:58:38 INFO - 'DCLOCATION': 'SCL3',
13:58:38 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
13:58:38 INFO - 'FP_NO_HOST_CHECK': 'NO',
13:58:38 INFO - 'HOMEDRIVE': 'C:',
13:58:38 INFO - 'HOMEPATH': '\\Users\\cltbld',
13:58:38 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
13:58:38 INFO - 'KTS_VERSION': '1.19c',
13:58:38 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
13:58:38 INFO - 'LOGONSERVER': '\\\\T-W732-IX-106',
13:58:38 INFO - 'MONDIR': 'C:\\Monitor_config\\',
13:58:38 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
13:58:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:58:38 INFO - 'MOZ_NO_REMOTE': '1',
13:58:38 INFO - 'NO_EM_RESTART': '1',
13:58:38 INFO - 'NUMBER_OF_PROCESSORS': '8',
13:58:38 INFO - 'OS': 'Windows_NT',
13:58:38 INFO - 'OURDRIVE': 'C:',
13:58:38 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
13:58:38 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
13:58:38 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
13:58:38 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
13:58:38 INFO - 'PROCESSOR_LEVEL': '6',
13:58:38 INFO - 'PROCESSOR_REVISION': '1e05',
13:58:38 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
13:58:38 INFO - 'PROGRAMFILES': 'C:\\Program Files',
13:58:38 INFO - 'PROMPT': '$P$G',
13:58:38 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
13:58:38 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
13:58:38 INFO - 'PUBLIC': 'C:\\Users\\Public',
13:58:38 INFO - 'PWD': 'C:\\slave\\test',
13:58:38 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
13:58:38 INFO - 'SLAVEDIR': 'C:\\slave\\',
13:58:38 INFO - 'SYSTEMDRIVE': 'C:',
13:58:38 INFO - 'SYSTEMROOT': 'C:\\windows',
13:58:38 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
13:58:38 INFO - 'TEST1': 'testie',
13:58:38 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
13:58:38 INFO - 'USERDOMAIN': 'T-W732-IX-106',
13:58:38 INFO - 'USERNAME': 'cltbld',
13:58:38 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
13:58:38 INFO - 'WINDIR': 'C:\\windows',
13:58:38 INFO - 'WINDOWS_TRACING_FLAGS': '3',
13:58:38 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
13:58:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
13:58:38 INFO - Ignoring indexes: https://pypi.python.org/simple/
13:58:38 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages
13:58:38 INFO - Cleaning up...
13:58:38 INFO - Return code: 0
13:58:38 INFO - Running post-action listener: _resource_record_post_action
13:58:38 INFO - Running post-action listener: _start_resource_monitoring
13:58:38 INFO - Starting resource monitoring.
13:58:39 INFO - [mozharness: 2016-09-27 20:58:39.044000Z] Finished create-virtualenv step (success)
13:58:39 INFO - [mozharness: 2016-09-27 20:58:39.044000Z] Running install step.
13:58:39 INFO - Running pre-action listener: _resource_record_pre_action
13:58:39 INFO - Running main action method: install
13:58:39 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
13:58:39 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
13:58:39 INFO - Reading from file tmpfile_stdout
13:58:39 INFO - Using _rmtree_windows ...
13:58:39 INFO - Using _rmtree_windows ...
13:58:39 INFO - Detecting whether we're running mozinstall >=1.0...
13:58:39 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h']
13:58:39 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h
13:58:39 INFO - Reading from file tmpfile_stdout
13:58:39 INFO - Output received:
13:58:39 INFO - Usage: mozinstall-script.py [options] installer
13:58:39 INFO - Options:
13:58:39 INFO - -h, --help show this help message and exit
13:58:39 INFO - -d DEST, --destination=DEST
13:58:39 INFO - Directory to install application into. [default:
13:58:39 INFO - "C:\slave\test"]
13:58:39 INFO - --app=APP Application being installed. [default: firefox]
13:58:39 INFO - Using _rmtree_windows ...
13:58:39 INFO - Using _rmtree_windows ...
13:58:39 INFO - mkdir: C:\slave\test\build\application
13:58:39 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.zip', '--destination', 'C:\\slave\\test\\build\\application']
13:58:39 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py C:\slave\test\build\firefox-52.0a1.en-US.win32.zip --destination C:\slave\test\build\application
13:58:41 INFO - Reading from file tmpfile_stdout
13:58:41 INFO - Output received:
13:58:41 INFO - C:\slave\test\build\application\firefox\firefox.exe
13:58:41 INFO - Using _rmtree_windows ...
13:58:41 INFO - Using _rmtree_windows ...
13:58:41 INFO - Running post-action listener: _resource_record_post_action
13:58:41 INFO - [mozharness: 2016-09-27 20:58:41.848000Z] Finished install step (success)
13:58:41 INFO - [mozharness: 2016-09-27 20:58:41.848000Z] Running run-tests step.
13:58:41 INFO - Running pre-action listener: _resource_record_pre_action
13:58:41 INFO - Running main action method: run_tests
13:58:41 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version']
13:58:41 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version
13:58:41 INFO - Python 2.7.3
13:58:41 INFO - Return code: 0
13:58:41 INFO - grabbing minidump binary from tooltool
13:58:41 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')]}
13:58:41 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 0x01869E90>, '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 0x0126A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x018D5520>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1
13:58:41 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
13:58:41 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
13:58:41 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
13:58:41 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'...
13:58:42 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmp3wgkya
13:58:42 INFO - INFO - File integrity verified, renaming tmp3wgkya to win32-minidump_stackwalk.exe
13:58:42 INFO - Return code: 0
13:58:42 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755
13:58:42 INFO - mkdir: C:\slave\test\build\blobber_upload_dir
13:58:42 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir
13:58:42 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe
13:58:42 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir
13:58:42 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/edqbP4-VScWWYnLLvUL6LA/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-106', '--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
13:58:42 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/edqbP4-VScWWYnLLvUL6LA/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-106 --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
13:58:42 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
13:58:42 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
13:58:42 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
13:58:42 INFO - 'COMPUTERNAME': 'T-W732-IX-106',
13:58:42 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
13:58:42 INFO - 'CYGWINBASE': 'C:\\cygwin',
13:58:42 INFO - 'DCLOCATION': 'SCL3',
13:58:42 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
13:58:42 INFO - 'FP_NO_HOST_CHECK': 'NO',
13:58:42 INFO - 'HOMEDRIVE': 'C:',
13:58:42 INFO - 'HOMEPATH': '\\Users\\cltbld',
13:58:42 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
13:58:42 INFO - 'KTS_VERSION': '1.19c',
13:58:42 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
13:58:42 INFO - 'LOGONSERVER': '\\\\T-W732-IX-106',
13:58:42 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir',
13:58:42 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe',
13:58:42 INFO - 'MONDIR': 'C:\\Monitor_config\\',
13:58:42 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
13:58:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:58:42 INFO - 'MOZ_NO_REMOTE': '1',
13:58:42 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir',
13:58:42 INFO - 'NO_EM_RESTART': '1',
13:58:42 INFO - 'NUMBER_OF_PROCESSORS': '8',
13:58:42 INFO - 'OS': 'Windows_NT',
13:58:42 INFO - 'OURDRIVE': 'C:',
13:58:42 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
13:58:42 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
13:58:42 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
13:58:42 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
13:58:43 INFO - 'PROCESSOR_LEVEL': '6',
13:58:43 INFO - 'PROCESSOR_REVISION': '1e05',
13:58:43 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
13:58:43 INFO - 'PROGRAMFILES': 'C:\\Program Files',
13:58:43 INFO - 'PROMPT': '$P$G',
13:58:43 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
13:58:43 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
13:58:43 INFO - 'PUBLIC': 'C:\\Users\\Public',
13:58:43 INFO - 'PWD': 'C:\\slave\\test',
13:58:43 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos',
13:58:43 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
13:58:43 INFO - 'SLAVEDIR': 'C:\\slave\\',
13:58:43 INFO - 'SYSTEMDRIVE': 'C:',
13:58:43 INFO - 'SYSTEMROOT': 'C:\\windows',
13:58:43 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
13:58:43 INFO - 'TEST1': 'testie',
13:58:43 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
13:58:43 INFO - 'USERDOMAIN': 'T-W732-IX-106',
13:58:43 INFO - 'USERNAME': 'cltbld',
13:58:43 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
13:58:43 INFO - 'WINDIR': 'C:\\windows',
13:58:43 INFO - 'WINDOWS_TRACING_FLAGS': '3',
13:58:43 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
13:58:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
13:58:43 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/edqbP4-VScWWYnLLvUL6LA/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-106', '--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
13:58:44 INFO - mozversion application_buildid: 20160927093046
13:58:44 INFO - mozversion application_changeset: b0d4f06aabd64fe182c1f145d70c90681d91028f
13:58:44 INFO - mozversion application_display_name: Nightly
13:58:44 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
13:58:44 INFO - mozversion application_name: Firefox
13:58:44 INFO - mozversion application_remotingname: firefox
13:58:44 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team
13:58:44 INFO - mozversion application_vendor: Mozilla
13:58:44 INFO - mozversion application_version: 52.0a1
13:58:44 INFO - mozversion platform_buildid: 20160927093046
13:58:44 INFO - mozversion platform_changeset: b0d4f06aabd64fe182c1f145d70c90681d91028f
13:58:44 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team
13:58:44 INFO - mozversion platform_version: 52.0a1
13:58:44 INFO - using testdate: 1475009924
13:58:44 INFO - actual date: 1475009924
13:58:44 INFO - starting webserver on 'localhost:49891'
13:58:44 INFO - SUITE-START | Running 2 tests
13:58:44 INFO - TEST-START | dromaeo_css
13:58:44 INFO - operating with platform_type : w7_
13:58:44 INFO - Initialising browser for dromaeo_css test...
13:58:44 INFO - TEST-INFO | started process 2928 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmp6quali\profile http://localhost:49891/getInfo.html)
13:58:51 INFO - PROCESS | 2928 | __metrics Screen width/height:1600/1200
13:58:51 INFO - PROCESS | 2928 | colorDepth:24
13:58:51 INFO - PROCESS | 2928 | Browser inner width/height: 1010/674
13:58:51 INFO - PROCESS | 2928 | __metrics
13:58:52 INFO - PROCESS | 2928 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows"
13:58:52 INFO - PROCESS | 2928 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
13:58:53 INFO - TEST-INFO | 2928: exit 0
13:58:53 INFO - Browser initialized.
13:58:53 INFO - Running cycle 1/1 for dromaeo_css test...
13:58:53 INFO - TEST-INFO | started process 3868 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmp6quali\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1)
13:58:53 INFO - PROCESS | 3868 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0
13:59:04 INFO - PROCESS | 3868 | RSS: Main: 124375040
13:59:04 INFO - PROCESS | 3868 |
13:59:56 INFO - PROCESS | 3868 | 1475009996311 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:02:05 INFO - PROCESS | 3868 | Cycle 1(1): loaded http://localhost:49891/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49891/tests/dromaeo/cssquery-ext.html)
14:02:06 INFO - PROCESS | 3868 | RSS: Main: 196960256
14:02:06 INFO - PROCESS | 3868 |
14:05:08 INFO - PROCESS | 3868 | Cycle 1(1): loaded http://localhost:49891/tests/dromaeo/cssquery-ext.html (next: http://localhost:49891/tests/dromaeo/cssquery-jquery.html)
14:05:09 INFO - PROCESS | 3868 | RSS: Main: 198148096
14:05:09 INFO - PROCESS | 3868 |
14:08:11 INFO - PROCESS | 3868 | Cycle 1(1): loaded http://localhost:49891/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49891/tests/dromaeo/cssquery-mootools.html)
14:08:12 INFO - PROCESS | 3868 | RSS: Main: 151470080
14:08:12 INFO - PROCESS | 3868 |
14:11:14 INFO - PROCESS | 3868 | Cycle 1(1): loaded http://localhost:49891/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49891/tests/dromaeo/cssquery-prototype.html)
14:11:14 INFO - PROCESS | 3868 | RSS: Main: 141615104
14:11:14 INFO - PROCESS | 3868 |
14:14:17 INFO - PROCESS | 3868 | Cycle 1(1): loaded http://localhost:49891/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49891/tests/dromaeo/cssquery-yui.html)
14:14:18 INFO - PROCESS | 3868 | RSS: Main: 134434816
14:14:18 INFO - PROCESS | 3868 |
14:17:21 INFO - PROCESS | 3868 | Cycle 1(1): loaded http://localhost:49891/tests/dromaeo/cssquery-yui.html (next: http://localhost:49891/tests/dromaeo/cssquery-dojo.html)
14:17:21 INFO - PROCESS | 3868 | RSS: Main: 196603904
14:17:21 INFO - PROCESS | 3868 |
14:17:21 INFO - PROCESS | 3868 | __start_tp_report
14:17:21 INFO - PROCESS | 3868 | _x_x_mozilla_page_load
14:17:21 INFO - PROCESS | 3868 | _x_x_mozilla_page_load_details
14:17:21 INFO - PROCESS | 3868 | |i|pagename|runs|
14:17:21 INFO - PROCESS | 3868 | |0;dojo.html;2730.1127133681553;2739.6301499297588;2749.20273120795;2761.1992522168566;2775.028739941021;4668.859934201975;4720.079584481024;4773.307870358796;4793.520647935209;4830.83092091776;4531.592156705896;4624.791884365203;4685.695429797061;4794.352762377078;4843.612510999121;4130.628243458088;4205.179989901971;4210.09482961164;4218.177455396199;4222.535521092677;4066.6340029397347;4183.330667093266;4201.474815648044;4217.8312867485265;4237.427947227131;3894.610538946111;4203.4325366075645;4210.263203939314;4215.3255479123345;4229.175310814392;4078.551359350484;4190.727602705814;4202.306619407794;4234.85178018771;4271.914561708749;4087.4890638670167;4127.195196936598;4151.107511884959;4237.449131612871;4240.279152544075;4649.86050418488;4705.058988202339;4726.361941137937;4758.381410416659;4763.64272679547;4945.184044632631;4964.826231081931;5048.570871475892;5053.620978426596;5066.923996140061;4814.470408255089;4861.611071114337;4871.537203965618;4976.2286845539;5097.541221290102;4682.906341873144;4701.3653156824075;4706.152892479352;4714.434267887876;4718.811247550094;5078.822241221539;5096.770645320969;5192.688438693691;5197.66215196011;5209.817656382009;4732.432108146975;4872.878178045577;4880.389951256093;4881.096997055556;4993.9512702332195;4977.776000079933;4995.875103122378;5006.724630145377;5071.492850714899;5088.592912566985;4932.630052746058;4938.358013458227;5037.748112594356;5043.9747801260755;5047.722375269322;4840.975795121002;4900.117978763786;4959.504049595012;5011.373578302712;5030.597552195815;3325.3681800457834;3331.3836940166148;3332.666733326648;3334.7999120052873;3335.0995231287416;3051.649060358043;3058.388322335542;3071.4010767900045;3090.752739780812;3096.4735994880925;4706.270528068155;4712.151812673752;4796.784144713496;4801.4718380978065;4805.27920811881;4911.852644420673;4955.1823949048;4959.528844759814;4995.25071239317;5022.096022715873;4683.461401938821;4728.38530990969;4746.0745154694505;4754.286856971482;4755.001449695534;4065.6747460203114;4155.750654960712;4159.958400416018;4165.291900376943;4169.56219596944;5030.773615187399;5225.843224703265;5290;5290.709011004432;5304.549113325334;4730.739809310383;5036.974815125901;5043.420006699276;5044.0920634286185;5047.823326183566;5203.16749320107;5247.868803279948;5272.947270527399;5277.683338999673;5284.682919024871;4934.506549345037;4954.232094025288;4956.553910148104;4957.677750946177;4959.330490383896;4960.652754307308;4999.350084488993;5045.974770126126;5059.924101138559;5105.2342148678;4843.346148269939;4869.756512174448;4916.532929371704;4946.505349465025;4948.356713627206;4651.418572678415;4817.518248175154;4869.123557759637;4897.583705385082;4958.057968985881;4756.619470442425;4835.081334546425;4836.637252206028;4853.980664060587;4863.2461968394955;4852.611791056777;4860.562549370574;4956.925646115239;4960.20636698141;4963.131451995959;4787.257975013878;4805.735684537384;4831.806727730992;4882.4629290777275;4886.169351210232
14:17:21 INFO - PROCESS | 3868 | |1;ext.html;530.9150535914254;562.2858969109232;565.5051829649057;574.4973148495069;589.4547543522241;29571.704282957166;40922.59077409225;41402.17195656085;41416.58583414169;44191.33712994299;43126.56873431265;43224;43520.347194792106;43526.12947741051;43544.34683479751;8578.871316930252;8694.521801300925;8740.737777866643;8747.518886461243;8751.78120546985;8552.657893684245;8560.229579337858;8594.183552562497;8611.56942152893;8642.567871606427;8250;8647.048824629286;8662.913370866274;8665.740027799144;8679.915010623672;8210.753677389688;8478.321734261244;8595.914040859605;8626.913730862674;8646.697365592205;8146.551939643317;8448.366372522023;8511.659533618647;8512.276456501142;8521.616527256288;38296.808515957244;40105;40423.393649095284;40747;40767.59232407668;3307.1897385140555;3452.205992621711;3542.8405721742583;3824.7896365699876;3972.7020473464604;31110.53334199989;32105.678943210503;32325;32401.83799081013;33378.83310583456;3397.4394224952853;3538.628444013393;3552.964470355289;3577.910552236189;3629.4737263096704;370743;381241;383942;385732;389532;56462.71768641213;56779.43220567741;56791.432085678614;56798.7160064197;56807.715961419926;27957.440851182862;57317.426825732044;58690.41309586935;58825.70587147037;58892;15026.549203523911;15721;15784.68430631381;15804.36782528709;15867.841321586637;11688.18182727218;12021.278723276631;12076.215046022156;12133.635990920286;12145;17676;17792;17842;17916.462506124837;17933.641327173646;6924.515283930177;6927.965360173268;6957.443404527626;6983.685734141975;7005.509614326948;809.0736107157272;864.8745931839848;870.5647176411794;884.7080463447176;918.6417297253943;26787.732122678914;28397;28471.715282846908;28715;29427.705722942927;882.7793051737066;906.954652267384;928.4382948316306;961.8268711631973;990.8513722941616;14303;14565.708685826436;14849.183294918883;15034;15227.771583426258;536525;537813;538807;543701;544603;50770;52363.476365236624;52644.2091369949;52724.73637631787;52834.471655282956;1171.9003884669728;1202.7955247607927;1210.6973256685828;1211.6425654431787;1218.670958841108;26742.465150697266;27363.589546156425;28023;28225.15324540267;28543.857280713462;46082.539174607824;46441.53558464507;46617.06765864596;46862.29706554336;46898.54463825931;468340;485305;486589;486598;487608;19201.61596768029;20026.799732003077;21964.89017554902;22769.430764231027;23239.186628467924;43286.56713432825;44867.775661121486;44883.326750099426;45123.29260244396;45162.09675806531;37143.44284835784;37467.36098380351;37751.62248377591;38457.807710961264;39900.80049599733;37566.43650345302;38078.238435231695;38202.23595528129;38734.22531549297;39109.41335880021;33887.129225681434;34148.487772683926;35399.82300088483;36020.63979360173;36135.27729445344;33503.329933401685;34047.65952340544;34145;34231.48652770161;34455.483167752005;25090.24729258125;25587.36031599225;25652.61521077148;25734.485310293316;25790.47485907962
14:17:21 INFO - PROCESS | 3868 | |2;jquery.html;279.1541628864539;298.49703250023714;310.4969948683134;314.39008323851726;314.5753233135268;13236.272005039731;16855.325786968522;17327.306907723676;17390.82609173906;17643.206055727493;16682.33270669172;16790.58023549412;16961.91519042403;17040.403585874497;17117.82882171178;4941.924391755462;5122.641415100945;5129.281900533928;5134.1528647773075;5173.405058418292;4981.75456135966;5096.439391666913;5114.181730923053;5161.690298582097;5272.340957380327;5056.823011194609;5064.3163172971545;5065.721385323805;5068.391792984847;5149;5020.397552293712;5061.54446099851;5100.33695619569;5305.124654431995;5356.892862142735;4405.383246345515;4406.955930440687;4424.557544245549;4444.644428445717;4656.5343465653505;21729.67405488919;21777.455563610878;22031.017208451318;22154.778452215432;23022.654660179942;1790.7224380220957;1832.6243120160334;1917.5973045660428;1933.9033048347526;1967.2032826705065;13895.58313250594;14198;14289.499867504692;14357.712845743026;14564.198969056697;1693.187270110354;1728.6369862328927;1731.463246393622;1731.8354756298259;1737.444017914268;84319.5784021082;84370;84986;85447.57276213642;86271;8717.389782715276;8860.911390886009;9036.502992335485;9112.908870911338;9149.54252287383;10187.7453063674;10812.729681758019;11317.320960742381;11348.602798901999;11820;7235;7327.816804579928;7336.7798966031105;7349.669264883093;7417.888731669029;7374.963125184339;7475.823643652774;7483.176850546435;7529.435292353095;7619.733309334147;3640.381135207021;3650;3677.852885884588;3720.4419337099653;3721.5720192177705;1482.9332680029208;1495.7681559358316;1516.673915108257;1516.8407317231752;1523.9238038098272;769.8845173224062;802.7591722483231;849.180540778152;872.5680788009976;895.6104094718818;17186.31254749821;17536.561585960197;17561;18016.549586260448;18041;803.4014659079023;814.8451794159091;864.179029921577;893.2407453664333;894.4901406198404;8734.170253825876;9876.851847222297;9982.151517120981;10060.24548158891;10131.138853197413;374516;377923;380691;383192;385486;89121.55439222763;91881;92312.53843730739;92970;95316;834.2616784146074;853.8548446763941;854.8974123105143;855.0252711908429;855.1064137975831;15296.694066118838;15417.614559636098;15462;15632.687346253239;15813.525594232191;39516.40725389179;42146.36780448235;44666.3300050493;45222;49557.50442495673;58957.41042589519;62880.42798930063;63148.36851631425;63389;65048.699026020164;11459.656210313704;11660.41697915084;11818.82271765907;12095.697607559881;12328.75342493163;7757.961210194138;7808.88286675689;7825.373970082266;8077.959610202147;8242.711505097534;6871.347222013901;6994.965025174842;7131.679074441558;7304.707811687473;7418.703251870081;7092.468064895257;7113.53762005468;7349.963250183715;7388.593627350548;7400.592967386845;7065.328793764585;7164;7201.963990180016;7266.600336981517;7492.100947886289;11686.766264674488;12079.69800754953;12094.455749491119;12282.877171228174;12583.93708031454;10941.671749847517;11084.722881928017;11132.332060076422;11154.498047587716;11305.434728263719
14:17:21 INFO - PROCESS | 3868 | |3;mootools.html;1093.9671809845697;1172;1214.6841821126507;1278.4566559212335;1465.457780621171;6071.392860713926;6613.3056029116915;6812.591244525332;6963.79108626742;7023.227444981048;6720.227173875006;6774.559653622511;6775.322467753223;6776.491763117761;6954.8261293467685;1724.9741253881202;1741.286072710189;1752.229019231534;1757.0511923561262;1784.5627821183828;1705.190034733498;1735.297204632126;1739.8434140927313;1783.9464816055538;1785.3572713823014;1712.5975395781982;1713.8543223825989;1725.4737305121932;1746.1967494952337;1796.6496533176037;1678.3035040458135;1692;1726.5165753589015;1737.6090379664602;1738.3394310162184;1682.343885884506;1688.9324427022905;1689.1554222888556;1690.4421540891476;1696.770935923647;6598.406143447065;6720.361565651256;7038.260982596856;7049.612271325075;7093.787186384417;3931.4495970564144;4054.614811592894;4082.101937573729;4084.7133153056693;4140.358244472111;2628.461165461076;2684.9865750671315;2726.5364887968894;2748.3266599683207;2779.0967935420867;789.9328557072656;821.6631181215732;823.6993497373476;828.2628460670009;830.3772170871846;8750.212480876751;8835.851339325847;8909;9031.638734450615;9046.140616641409;3430.6054803697893;3516.5604299462566;3538.6992105670793;3606.13452771333;3609.801460919622;7803.687852485837;7837.529748215125;7874.645640946172;8025.157358477392;8310.21052999964;3981.6814654827544;4090.795460226977;4099.795010249476;4178.206140833232;4189.958100419018;3387.81367024816;3393.406153923054;3766.7174961877968;3770.453284273765;3786.962130378716;2901.347196880685;2960.792744507864;2963.3628769814595;2968.9703102968692;3013.668496465387;920.9953950230206;921.4102974096584;939.9201067909167;941.5904081724472;947.625687853294;690.5304393012799;729.7445893937186;753.2053683363994;768.454397377868;769.5613500304772;4327.567243275647;4367.606915377632;4439.755813430293;4599.862004139881;4679.415073115861;784.196198896136;797.2187256488673;807.8545861744942;824.6536454689045;848.8854004709409;3214.2767877227902;3314.9392194497527;3329.234276116458;3387.4241378965635;3440.8795692150657;8236.752897413087;8321.916780832235;8385.28725058377;8396.454230475121;8435.915640843636;8172.346212303001;8276;8495.447795893371;8503.404761666749;8580.442271252348;6417.550771446047;6457.741690332428;6477.4494167996245;6591.835204119935;6803.251642319341;4251.59609837065;4364.716293440916;4457.487388950182;4575.656825738017;4740.672611668739;7314.743983960536;7565.470417070752;7646.732364367443;7797.259260370256;7838.725644602411;7618.961905190438;7772.727954521564;7888.645010974635;7896.289333959971;7899.881501777593;6060.606060606046;6152.384761523812;6186.288576813544;6234.407731265523;6390.233172019387;6148.538859585459;6260.436560709558;6371.87256254863;6382.648954307558;6486.5783724059675;6017.759289628366;6018.8194354169445;6040.305364882919;6093.2078829751845;6108.938910610837;5695.45893140151;5859.267591551056;5870.618409803349;5884.47039766423;5957.523398128226;5486.835394938158;5541.750621221974;5547.7780888764;5620.819627878192;5854.619449735754;3930.272899513599;3940.211957608547;3944.9605503944595;3993.92012159761;4111.794410279534;4068.186362727407;4080.3879418087527;4124.649404800625;4132.524759652558;4303.634191093792
14:17:21 INFO - PROCESS | 3868 | |4;prototype.html;241.1271198262291;243.27989152109754;250.6716201775674;251.47316372200234;254.05744687210455;4921.655484116113;4949.663590830473;4954.256861470782;5089.312942752732;5098.694078355301;4579.381783459232;4685.83599574015;4853.805847766094;4921.606271498281;4984.202527595586;3541.575010998684;3573.821308934556;3624.2751449710163;3625.166211771281;3648.2521083177953;3533.628968958261;3534.5051692762904;3534.876279330224;3537.1864471171516;3556.982215088921;3344.8258631889225;3436.3986302397107;3446.7931924084637;3526.5239192708914;3548.201654627714;3505.5793304803333;3517.331706975679;3569.161247106928;3572.4462708280257;3645.635436456373;3028.5911401960675;3032.36320372722;3086.10502954143;3103.7361824244967;3189;4721.3390125382475;4733.810647574093;4873.31773551703;4933;4966.279889416014;3974.3442332015184;4071;4114.136031433372;4131.607497287753;4277.935830962538;4930.309756634074;4939.135651261015;4983.15286401309;5152.6650767699975;5177.404598471187;3325.900222993314;3358.462645976632;3373.8528900173815;3496.423090190141;3525.7531972761917;5211.644972307227;5451.909618076401;5478.150886612581;5489.835304940858;5506.697131657717;3438.7420943429343;3442.019024577983;3488.3720930232803;3616.8968464618542;3617.4573813928123;3438.1404648837793;3489.860405583748;3566.892993210208;3626.0028492164865;3657.8843452746637;4068.2303198108916;4073.165001174752;4090.7136500445276;4135.883311505877;4242.960474683722;4995.575376093;4996.750812296926;5010.974945125251;5027.396712394463;5207.583393328525;1215.750771091924;1224.179799534314;1237.0783766094316;1243.8880500754794;1260.6281147061638;761.2387612387612;768.2317682317682;769.2077160524668;779.8440311937635;798.5368486277945;4983.377077865267;4996.925046124311;5044.344235249468;5115.8721031973755;5137.999999999925;5206.140986737221;5241.344831896013;5252.1596544552685;5261.894762104736;5316.282301889273;4823.855284341475;4878.902421951541;4905.779239934212;4918.385201849769;5034.270030845581;2633.065261832022;2762.7237276272613;2764.7649949754496;2801.033643393026;2832.3910359272854;5451.264079349237;5469.878674871642;5473;5620.747066382022;5691.26013618227;5249.396319423239;5251.76367063483;5256.894862102813;5267.973660131752;5273.736313184326;5250.579953603627;5446.972765136149;5466.879289745592;5468.535174510212;5485.451454854562;5250.238715386171;5258;5267.525922666979;5442.972785136049;5541.778328866801;5166;5193.818216362409;5216.869578260574;5328.973355133199;5444.401115877171;5579.693116878449;5787.305523337226;5794.3626201117;5820.476157145878;5861.912071319019;3199.6640352762624;3239.174010627275;3365.6634336567126;3501.562304711911;3542.2030043240065;5042.899142017212;5220.295260139985;5227.764750586157;5294.947050529446;5298.70857102863;5036.823711170092;5046.142155833444;5119.979212264781;5241.187615919539;5271.841844744664;4856.150173719656;5038.261799679251;5115.667481613683;5155.767990440364;5260.921086183634;4783.421470914659;4976.900461990812;4993.725345106054;5024.522670346311;5150.8969820604125;4950.306957026091;5112.463191365002;5146.099432599355;5177.948220517746;5363.570914326767;4926.48271931442;4990.428015175354;5072.137736584863;5101.719405432588;5223.242629818731
14:17:21 INFO - PROCESS | 3868 | |5;yui.html;632.2886752403546;634.6953462338075;663.2174034639127;666.7433038280261;696.578569965171;13576.389062492186;13644.863551364508;13979.161250324974;14294.428222871098;14509.419623215084;12490.250584964886;12574.119811613167;12892.677683057927;12904;12951.093423460361;497.7262505621912;617.6726335042434;618.3723520626545;630.1272737258906;634.0869148426275;498.663402203513;619.5632079384023;640.2285246278246;643.8454770854985;649.9187601549806;663.5355251324092;675.3550359406755;677.6002158726351;678.4945215814225;678.7488629207201;523.8795077132243;660.633348491587;665.1618960110271;666.8799616069106;676.2797620534116;388.8289152772771;470.82579445605;472.4967909176716;482.82376932419766;488.36024807502287;12813.871861281454;12863.356832158355;12961.611151665371;12994.96040316782;13061.673458163526;11922.225055371373;12078.456469458808;12190;12318.692032699164;12508.49966001359;11679.532818687243;11698.649040528713;11768.352740599265;11822.231554948901;12643.988480921504;4230.280852255124;4298.656107511423;4313.482382114166;4358.106588149421;4363.956360436387;80723;81644.59177704132;82930.58534707349;84124.57937710272;88128.11871881328;9842.50787460624;9908.950455247677;9951.353162044445;10269.664943557289;10537.63118290856;18880.62238755245;18936.526586835163;18959.526011849815;19669.40991770249;19679.40961771149;7286.162091359455;7288.96355518219;7301.269873012656;7474.514156579805;7501.512401693982;3909.8240579174008;3917.1535417059126;3988.4017397390044;4006.4591280177387;4060.3706425504092;2691.0177785108294;2777.680566734811;2788.9860550697117;2794.175718163147;2798.4962706712986;502.39461448954654;510.45636397236825;513.3172880069499;514.1747495270038;515.340364333646;604.9727762250709;606.8300875754795;612.537534161705;612.8976272531196;617.8517155882556;8535.274501667303;8780.42927209742;8794.460969330341;8838.116188381111;8914.420562663407;618.153130211613;627.6830200748593;628.355935166449;634.194572892424;635.6821589205397;4408.559144085566;4483.55164483549;4513.210188217115;4521.547845215452;4558.9772051139535;108001.4599927011;108186;108501;110881.89118108877;118630;63571;79968.20031799724;94589.52705236568;94992;103022;51722.48277517252;79062.60468697734;79874.20125798784;80339.59830200928;81943;8593.785155371166;8870.689525866565;9151;9233.630654774002;9401.529923503935;46395.304070438295;47924;48823;50380.49619503758;51372.22941655803;47855;59744.20767376986;65782;69818.65090674514;73625.63187184029;11964.700882478008;12041.33772642513;12249.203801752858;12412.441440135397;12637.241765493733;14346.641333966734;14450.699437050716;14934.477293294682;14979.625509362353;15001.174935378658;13186.208827470382;13384.19694818314;13636.863631363956;13859.30703464843;13987.930060349634;12648.747025059263;13952.720945580828;14036.596340365882;14057.22685252321;14364.712705746035;13121.212727236396;13335.399907004403;13728.244946527637;13841.723165536834;14067.648308792363;11253.943730281297;11527.827082593936;11627.604687437224;11669.76660466803;11789;11187.384693841917;11564.42177891119;11651.0096641783;11657;11728.237664551776
14:17:21 INFO - PROCESS | 3868 | __end_tp_report
14:17:21 INFO - PROCESS | 3868 | __start_cc_report
14:17:21 INFO - PROCESS | 3868 | _x_x_mozilla_cycle_collect,941
14:17:21 INFO - PROCESS | 3868 | __end_cc_report
14:17:21 INFO - PROCESS | 3868 | __startTimestamp1475011041345__endTimestamp
14:17:21 INFO - PROCESS | 3868 |
14:17:21 INFO - PROCESS | 3868 | ------- Summary: start -------
14:17:21 INFO - PROCESS | 3868 | Number of tests: 6
14:17:21 INFO - PROCESS | 3868 |
14:17:21 INFO - PROCESS | 3868 | [#0] dojo.html Cycles:165 Average:4614.12 Median:4831.81 stddev:594.48 (12.3%) stddev-sans-first:577.64
14:17:21 INFO - PROCESS | 3868 | Values: 2730.1 2739.6 2749.2 2761.2 2775.0 4668.9 4720.1 4773.3 4793.5 4830.8 4531.6 4624.8 4685.7 4794.4 4843.6 4130.6 4205.2 4210.1 4218.2 4222.5 4066.6 4183.3 4201.5 4217.8 4237.4 3894.6 4203.4 4210.3 4215.3 4229.2 4078.6 4190.7 4202.3 4234.9 4271.9 4087.5 4127.2 4151.1 4237.4 4240.3 4649.9 4705.1 4726.4 4758.4 4763.6 4945.2 4964.8 5048.6 5053.6 5066.9 4814.5 4861.6 4871.5 4976.2 5097.5 4682.9 4701.4 4706.2 4714.4 4718.8 5078.8 5096.8 5192.7 5197.7 5209.8 4732.4 4872.9 4880.4 4881.1 4994.0 4977.8 4995.9 5006.7 5071.5 5088.6 4932.6 4938.4 5037.7 5044.0 5047.7 4841.0 4900.1 4959.5 5011.4 5030.6 3325.4 3331.4 3332.7 3334.8 3335.1 3051.6 3058.4 3071.4 3090.8 3096.5 4706.3 4712.2 4796.8 4801.5 4805.3 4911.9 4955.2 4959.5 4995.3 5022.1 4683.5 4728.4 4746.1 4754.3 4755.0 4065.7 4155.8 4160.0 4165.3 4169.6 5030.8 5225.8 5290.0 5290.7 5304.5 4730.7 5037.0 5043.4 5044.1 5047.8 5203.2 5247.9 5272.9 5277.7 5284.7 4934.5 4954.2 4956.6 4957.7 4959.3 4960.7 4999.4 5046.0 5059.9 5105.2 4843.3 4869.8 4916.5 4946.5 4948.4 4651.4 4817.5 4869.1 4897.6 4958.1 4756.6 4835.1 4836.6 4854.0 4863.2 4852.6 4860.6 4956.9 4960.2 4963.1 4787.3 4805.7 4831.8 4882.5 4886.2
14:17:21 INFO - PROCESS | 3868 |
14:17:21 INFO - PROCESS | 3868 | [#1] ext.html Cycles:180 Average:60634.18 Median:40757.30 stddev:125899.62 (308.9%) stddev-sans-first:126171.93
14:17:21 INFO - PROCESS | 3868 | Values: 530.9 562.3 565.5 574.5 589.5 29571.7 40922.6 41402.2 41416.6 44191.3 43126.6 43224.0 43520.3 43526.1 43544.3 8578.9 8694.5 8740.7 8747.5 8751.8 8552.7 8560.2 8594.2 8611.6 8642.6 8250.0 8647.0 8662.9 8665.7 8679.9 8210.8 8478.3 8595.9 8626.9 8646.7 8146.6 8448.4 8511.7 8512.3 8521.6 38296.8 40105.0 40423.4 40747.0 40767.6 3307.2 3452.2 3542.8 3824.8 3972.7 31110.5 32105.7 32325.0 32401.8 33378.8 3397.4 3538.6 3553.0 3577.9 3629.5 370743.0 381241.0 383942.0 385732.0 389532.0 56462.7 56779.4 56791.4 56798.7 56807.7 27957.4 57317.4 58690.4 58825.7 58892.0 15026.5 15721.0 15784.7 15804.4 15867.8 11688.2 12021.3 12076.2 12133.6 12145.0 17676.0 17792.0 17842.0 17916.5 17933.6 6924.5 6928.0 6957.4 6983.7 7005.5 809.1 864.9 870.6 884.7 918.6 26787.7 28397.0 28471.7 28715.0 29427.7 882.8 907.0 928.4 961.8 990.9 14303.0 14565.7 14849.2 15034.0 15227.8 536525.0 537813.0 538807.0 543701.0 544603.0 50770.0 52363.5 52644.2 52724.7 52834.5 1171.9 1202.8 1210.7 1211.6 1218.7 26742.5 27363.6 28023.0 28225.2 28543.9 46082.5 46441.5 46617.1 46862.3 46898.5 468340.0 485305.0 486589.0 486598.0 487608.0 19201.6 20026.8 21964.9 22769.4 23239.2 43286.6 44867.8 44883.3 45123.3 45162.1 37143.4 37467.4 37751.6 38457.8 39900.8 37566.4 38078.2 38202.2 38734.2 39109.4 33887.1 34148.5 35399.8 36020.6 36135.3 33503.3 34047.7 34145.0 34231.5 34455.5 25090.2 25587.4 25652.6 25734.5 25790.5
14:17:21 INFO - PROCESS | 3868 |
14:17:21 INFO - PROCESS | 3868 | [#2] jquery.html Cycles:180 Average:25379.44 Median:4799.23 stddev:63897.35 (1331.4%) stddev-sans-first:64048.81
14:17:21 INFO - PROCESS | 3868 | Values: 279.2 298.5 310.5 314.4 314.6 13236.3 16855.3 17327.3 17390.8 17643.2 16682.3 16790.6 16961.9 17040.4 17117.8 4941.9 5122.6 5129.3 5134.2 5173.4 4981.8 5096.4 5114.2 5161.7 5272.3 5056.8 5064.3 5065.7 5068.4 5149.0 5020.4 5061.5 5100.3 5305.1 5356.9 4405.4 4407.0 4424.6 4444.6 4656.5 21729.7 21777.5 22031.0 22154.8 23022.7 1790.7 1832.6 1917.6 1933.9 1967.2 13895.6 14198.0 14289.5 14357.7 14564.2 1693.2 1728.6 1731.5 1731.8 1737.4 84319.6 84370.0 84986.0 85447.6 86271.0 8717.4 8860.9 9036.5 9112.9 9149.5 10187.7 10812.7 11317.3 11348.6 11820.0 7235.0 7327.8 7336.8 7349.7 7417.9 7375.0 7475.8 7483.2 7529.4 7619.7 3640.4 3650.0 3677.9 3720.4 3721.6 1482.9 1495.8 1516.7 1516.8 1523.9 769.9 802.8 849.2 872.6 895.6 17186.3 17536.6 17561.0 18016.5 18041.0 803.4 814.8 864.2 893.2 894.5 8734.2 9876.9 9982.2 10060.2 10131.1 374516.0 377923.0 380691.0 383192.0 385486.0 89121.6 91881.0 92312.5 92970.0 95316.0 834.3 853.9 854.9 855.0 855.1 15296.7 15417.6 15462.0 15632.7 15813.5 39516.4 42146.4 44666.3 45222.0 49557.5 58957.4 62880.4 63148.4 63389.0 65048.7 11459.7 11660.4 11818.8 12095.7 12328.8 7758.0 7808.9 7825.4 8078.0 8242.7 6871.3 6995.0 7131.7 7304.7 7418.7 7092.5 7113.5 7350.0 7388.6 7400.6 7065.3 7164.0 7202.0 7266.6 7492.1 11686.8 12079.7 12094.5 12282.9 12583.9 10941.7 11084.7 11132.3 11154.5 11305.4
14:17:21 INFO - PROCESS | 3868 |
14:17:21 INFO - PROCESS | 3868 | [#3] mootools.html Cycles:180 Average:4464.45 Median:5856.94 stddev:2518.33 (43.0%) stddev-sans-first:2512.66
14:17:21 INFO - PROCESS | 3868 | Values: 1094.0 1172.0 1214.7 1278.5 1465.5 6071.4 6613.3 6812.6 6963.8 7023.2 6720.2 6774.6 6775.3 6776.5 6954.8 1725.0 1741.3 1752.2 1757.1 1784.6 1705.2 1735.3 1739.8 1783.9 1785.4 1712.6 1713.9 1725.5 1746.2 1796.6 1678.3 1692.0 1726.5 1737.6 1738.3 1682.3 1688.9 1689.2 1690.4 1696.8 6598.4 6720.4 7038.3 7049.6 7093.8 3931.4 4054.6 4082.1 4084.7 4140.4 2628.5 2685.0 2726.5 2748.3 2779.1 789.9 821.7 823.7 828.3 830.4 8750.2 8835.9 8909.0 9031.6 9046.1 3430.6 3516.6 3538.7 3606.1 3609.8 7803.7 7837.5 7874.6 8025.2 8310.2 3981.7 4090.8 4099.8 4178.2 4190.0 3387.8 3393.4 3766.7 3770.5 3787.0 2901.3 2960.8 2963.4 2969.0 3013.7 921.0 921.4 939.9 941.6 947.6 690.5 729.7 753.2 768.5 769.6 4327.6 4367.6 4439.8 4599.9 4679.4 784.2 797.2 807.9 824.7 848.9 3214.3 3314.9 3329.2 3387.4 3440.9 8236.8 8321.9 8385.3 8396.5 8435.9 8172.3 8276.0 8495.4 8503.4 8580.4 6417.6 6457.7 6477.4 6591.8 6803.3 4251.6 4364.7 4457.5 4575.7 4740.7 7314.7 7565.5 7646.7 7797.3 7838.7 7619.0 7772.7 7888.6 7896.3 7899.9 6060.6 6152.4 6186.3 6234.4 6390.2 6148.5 6260.4 6371.9 6382.6 6486.6 6017.8 6018.8 6040.3 6093.2 6108.9 5695.5 5859.3 5870.6 5884.5 5957.5 5486.8 5541.8 5547.8 5620.8 5854.6 3930.3 3940.2 3945.0 3993.9 4111.8 4068.2 4080.4 4124.6 4132.5 4303.6
14:17:21 INFO - PROCESS | 3868 |
14:17:21 INFO - PROCESS | 3868 | [#4] prototype.html Cycles:180 Average:4250.50 Median:4960.27 stddev:1343.32 (27.1%) stddev-sans-first:1312.94
14:17:21 INFO - PROCESS | 3868 | Values: 241.1 243.3 250.7 251.5 254.1 4921.7 4949.7 4954.3 5089.3 5098.7 4579.4 4685.8 4853.8 4921.6 4984.2 3541.6 3573.8 3624.3 3625.2 3648.3 3533.6 3534.5 3534.9 3537.2 3557.0 3344.8 3436.4 3446.8 3526.5 3548.2 3505.6 3517.3 3569.2 3572.4 3645.6 3028.6 3032.4 3086.1 3103.7 3189.0 4721.3 4733.8 4873.3 4933.0 4966.3 3974.3 4071.0 4114.1 4131.6 4277.9 4930.3 4939.1 4983.2 5152.7 5177.4 3325.9 3358.5 3373.9 3496.4 3525.8 5211.6 5451.9 5478.2 5489.8 5506.7 3438.7 3442.0 3488.4 3616.9 3617.5 3438.1 3489.9 3566.9 3626.0 3657.9 4068.2 4073.2 4090.7 4135.9 4243.0 4995.6 4996.8 5011.0 5027.4 5207.6 1215.8 1224.2 1237.1 1243.9 1260.6 761.2 768.2 769.2 779.8 798.5 4983.4 4996.9 5044.3 5115.9 5138.0 5206.1 5241.3 5252.2 5261.9 5316.3 4823.9 4878.9 4905.8 4918.4 5034.3 2633.1 2762.7 2764.8 2801.0 2832.4 5451.3 5469.9 5473.0 5620.7 5691.3 5249.4 5251.8 5256.9 5268.0 5273.7 5250.6 5447.0 5466.9 5468.5 5485.5 5250.2 5258.0 5267.5 5443.0 5541.8 5166.0 5193.8 5216.9 5329.0 5444.4 5579.7 5787.3 5794.4 5820.5 5861.9 3199.7 3239.2 3365.7 3501.6 3542.2 5042.9 5220.3 5227.8 5294.9 5298.7 5036.8 5046.1 5120.0 5241.2 5271.8 4856.2 5038.3 5115.7 5155.8 5260.9 4783.4 4976.9 4993.7 5024.5 5150.9 4950.3 5112.5 5146.1 5177.9 5363.6 4926.5 4990.4 5072.1 5101.7 5223.2
14:17:21 INFO - PROCESS | 3868 |
14:17:21 INFO - PROCESS | 3868 | [#5] yui.html Cycles:180 Average:19416.30 Median:4498.38 stddev:28202.27 (626.9%) stddev-sans-first:28246.12
14:17:21 INFO - PROCESS | 3868 | Values: 632.3 634.7 663.2 666.7 696.6 13576.4 13644.9 13979.2 14294.4 14509.4 12490.3 12574.1 12892.7 12904.0 12951.1 497.7 617.7 618.4 630.1 634.1 498.7 619.6 640.2 643.8 649.9 663.5 675.4 677.6 678.5 678.7 523.9 660.6 665.2 666.9 676.3 388.8 470.8 472.5 482.8 488.4 12813.9 12863.4 12961.6 12995.0 13061.7 11922.2 12078.5 12190.0 12318.7 12508.5 11679.5 11698.6 11768.4 11822.2 12644.0 4230.3 4298.7 4313.5 4358.1 4364.0 80723.0 81644.6 82930.6 84124.6 88128.1 9842.5 9909.0 9951.4 10269.7 10537.6 18880.6 18936.5 18959.5 19669.4 19679.4 7286.2 7289.0 7301.3 7474.5 7501.5 3909.8 3917.2 3988.4 4006.5 4060.4 2691.0 2777.7 2789.0 2794.2 2798.5 502.4 510.5 513.3 514.2 515.3 605.0 606.8 612.5 612.9 617.9 8535.3 8780.4 8794.5 8838.1 8914.4 618.2 627.7 628.4 634.2 635.7 4408.6 4483.6 4513.2 4521.5 4559.0 108001.5 108186.0 108501.0 110881.9 118630.0 63571.0 79968.2 94589.5 94992.0 103022.0 51722.5 79062.6 79874.2 80339.6 81943.0 8593.8 8870.7 9151.0 9233.6 9401.5 46395.3 47924.0 48823.0 50380.5 51372.2 47855.0 59744.2 65782.0 69818.7 73625.6 11964.7 12041.3 12249.2 12412.4 12637.2 14346.6 14450.7 14934.5 14979.6 15001.2 13186.2 13384.2 13636.9 13859.3 13987.9 12648.7 13952.7 14036.6 14057.2 14364.7 13121.2 13335.4 13728.2 13841.7 14067.6 11253.9 11527.8 11627.6 11669.8 11789.0 11187.4 11564.4 11651.0 11657.0 11728.2
14:17:21 INFO - PROCESS | 3868 | -------- Summary: end --------
14:17:21 INFO - PROCESS | 3868 |
14:17:22 INFO - TEST-INFO | 3868: exit 0
14:17:22 INFO - TEST-OK | dromaeo_css | took 1118654ms
14:17:22 INFO - TEST-START | kraken
14:17:22 INFO - operating with platform_type : w7_
14:17:22 INFO - Initialising browser for kraken test...
14:17:22 INFO - TEST-INFO | started process 2832 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpyd30yi\profile http://localhost:49891/getInfo.html)
14:17:26 INFO - PROCESS | 2832 | __metrics Screen width/height:1600/1200
14:17:26 INFO - PROCESS | 2832 | colorDepth:24
14:17:26 INFO - PROCESS | 2832 | Browser inner width/height: 1010/674
14:17:26 INFO - PROCESS | 2832 | __metrics
14:17:27 INFO - PROCESS | 2832 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows"
14:17:27 INFO - PROCESS | 2832 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
14:17:28 INFO - PROCESS | 2832 | console.error:
14:17:28 INFO - PROCESS | 2832 | Message: Error: Connection closed before committing the transaction.
14:17:28 INFO - PROCESS | 2832 | Stack:
14:17:28 INFO - PROCESS | 2832 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:937:23
14:17:28 INFO - PROCESS | 2832 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7
14:17:28 INFO - PROCESS | 2832 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11
14:17:28 INFO - PROCESS | 2832 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7
14:17:28 INFO - PROCESS | 2832 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7
14:17:28 INFO - PROCESS | 2832 | TaskImpl_run@resource://gre/modules/Task.jsm:324:13
14:17:28 INFO - PROCESS | 2832 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9
14:17:28 INFO - PROCESS | 2832 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15
14:17:28 INFO - PROCESS | 2832 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5
14:17:28 INFO - PROCESS | 2832 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29
14:17:28 INFO - PROCESS | 2832 | Barrier/this.client.addBlocker/promise13.2%
14:18:26 INFO - TinderboxPrint: I/O read bytes / time
100,802,048 / 1,272,460
14:18:26 INFO - TinderboxPrint: I/O write bytes / time
489,707,008 / 3,984,800
14:18:26 INFO - TinderboxPrint: CPU idle
8,246.9 (86.8%)
14:18:26 INFO - TinderboxPrint: CPU user
1,209.6 (12.7%)
14:18:26 INFO - install - Wall time: 3s; CPU: 11.0%; Read bytes: 0; Write bytes: 35819520; Read time: 0; Write time: 392950
14:18:26 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 100802048; Write bytes: 416612352; Read time: 1272460; Write time: 3427210
14:18:26 INFO - Running post-run listener: _upload_blobber_files
14:18:26 INFO - Blob upload gear active.
14:18:26 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir.
14:18:26 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
14:18:26 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\venv\\Scripts\\blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', 'C:\\slave\\test\\oauth.txt', '-b', 'Fx-Team', '-d', 'C:\\slave\\test\\build\\blobber_upload_dir', '--output-manifest', 'C:\\slave\\test\\build\\uploaded_files.json']
14:18:26 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\venv\Scripts\blobberc.py -u https://blobupload.elasticbeanstalk.com -a C:\slave\test\oauth.txt -b Fx-Team -d C:\slave\test\build\blobber_upload_dir --output-manifest C:\slave\test\build\uploaded_files.json
14:18:27 INFO - (blobuploader) - INFO - Open directory for files ...
14:18:27 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log ...
14:18:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
14:18:27 INFO - (blobuploader) - INFO - Uploading, attempt #1.
14:18:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded
14:18:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
14:18:28 INFO - (blobuploader) - INFO - Done attempting.
14:18:28 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log ...
14:18:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
14:18:28 INFO - (blobuploader) - INFO - Uploading, attempt #1.
14:18:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded
14:18:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
14:18:29 INFO - (blobuploader) - INFO - Done attempting.
14:18:29 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ...
14:18:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
14:18:29 INFO - (blobuploader) - INFO - Uploading, attempt #1.
14:18:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
14:18:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
14:18:37 INFO - (blobuploader) - INFO - Done attempting.
14:18:37 INFO - (blobuploader) - INFO - Iteration through files over.
14:18:37 INFO - Return code: 0
14:18:37 INFO - rmtree: C:\slave\test\build\uploaded_files.json
14:18:37 INFO - Using _rmtree_windows ...
14:18:37 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1
14:18:37 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2443d7d8e1be1354ac130fa1cd563d4c0e3877000dec8b12579b4804399f7482d5dfd5093c5641d06b0ed21015893cc17c4dacedc49622302a2f419e4ab60a23", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d806b92e5dd086e868eb62740711679fd74212c379332ed1a46c6ca227cbcb8a972a8c08f955e620679079c4d555c9e8cd14a6e42f0f068bae76fe81ab35fab6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/24ba4f2462608ac146a17e9cbc81b195c92a60b454cbee8fa385fcf118df5d19b4950e3372e8da3236002004ed9ee53dacd82175756b520158ca68709cf9517d"}
14:18:37 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files
14:18:37 INFO - Writing to file C:\slave\test\properties\blobber_files
14:18:37 INFO - Contents:
14:18:37 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2443d7d8e1be1354ac130fa1cd563d4c0e3877000dec8b12579b4804399f7482d5dfd5093c5641d06b0ed21015893cc17c4dacedc49622302a2f419e4ab60a23", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d806b92e5dd086e868eb62740711679fd74212c379332ed1a46c6ca227cbcb8a972a8c08f955e620679079c4d555c9e8cd14a6e42f0f068bae76fe81ab35fab6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/24ba4f2462608ac146a17e9cbc81b195c92a60b454cbee8fa385fcf118df5d19b4950e3372e8da3236002004ed9ee53dacd82175756b520158ca68709cf9517d"}
14:18:37 INFO - Running post-run listener: copy_logs_to_upload_dir
14:18:37 INFO - Copying logs to upload dir...
14:18:37 INFO - mkdir: C:\slave\test\build\upload\logs
14:18:37 INFO - Copying logs to upload dir...
14:18:37 INFO - Using _rmtree_windows ...
14:18:37 INFO - Using _rmtree_windows ...
14:18:37 INFO - Using _rmtree_windows ...
14:18:37 INFO - Using _rmtree_windows ...
14:18:37 INFO - Using _rmtree_windows ...
14:18:37 INFO - Using _rmtree_windows ...
14:18:37 INFO - Using _rmtree_windows ...
program finished with exit code 0
elapsedTime=1437.642000
========= master_lag: 0.10 =========
========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 57 secs) (at 2016-09-27 14:18:37.702597) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-27 14:18:37.703400) =========
'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-106
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-106
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-106
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2443d7d8e1be1354ac130fa1cd563d4c0e3877000dec8b12579b4804399f7482d5dfd5093c5641d06b0ed21015893cc17c4dacedc49622302a2f419e4ab60a23", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d806b92e5dd086e868eb62740711679fd74212c379332ed1a46c6ca227cbcb8a972a8c08f955e620679079c4d555c9e8cd14a6e42f0f068bae76fe81ab35fab6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/24ba4f2462608ac146a17e9cbc81b195c92a60b454cbee8fa385fcf118df5d19b4950e3372e8da3236002004ed9ee53dacd82175756b520158ca68709cf9517d"}
build_url:https://queue.taskcluster.net/v1/task/edqbP4-VScWWYnLLvUL6LA/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
program finished with exit code 0
elapsedTime=0.101000
build_url: 'https://queue.taskcluster.net/v1/task/edqbP4-VScWWYnLLvUL6LA/artifacts/public/build/firefox-52.0a1.en-US.win32.zip'
blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2443d7d8e1be1354ac130fa1cd563d4c0e3877000dec8b12579b4804399f7482d5dfd5093c5641d06b0ed21015893cc17c4dacedc49622302a2f419e4ab60a23", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d806b92e5dd086e868eb62740711679fd74212c379332ed1a46c6ca227cbcb8a972a8c08f955e620679079c4d555c9e8cd14a6e42f0f068bae76fe81ab35fab6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/24ba4f2462608ac146a17e9cbc81b195c92a60b454cbee8fa385fcf118df5d19b4950e3372e8da3236002004ed9ee53dacd82175756b520158ca68709cf9517d"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-27 14:18:37.822855) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-27 14:18:37.823233) =========
'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-106
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-106
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-106
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
program finished with exit code 0
elapsedTime=0.101000
========= master_lag: 0.01 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-27 14:18:37.935980) =========
========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-09-27 14:18:37.936305) =========
========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-09-27 14:18:38.386206) =========
========= Total master_lag: 0.63 =========