builder: fx-team_win7_ix_test-dromaeojs
slave: t-w732-ix-140
starttime: 1475055696.1
results: success (0)
buildid: 20160928010920
builduid: 4c2b5161396b4448a18f710702628217
revision: 52cfd2babd94fa8452128940b50614cc12660785
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-28 02:41:36.100392) =========
master: http://buildbot-master119.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-28 02:41:36.100864) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-28 02:41:36.101208) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-28 02:41:36.330978) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-28 02:41:36.331284) =========
'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-140
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-140
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-140
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
--02:41:37-- 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% 2.10 GB/s
02:41:37 (2.10 GB/s) - `archiver_client.py' saved [12141/12141]
program finished with exit code 0
elapsedTime=1.235000
========= master_lag: 0.08 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-28 02:41:37.641449) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 11 secs) (at 2016-09-28 02:41:37.641970) =========
'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-140
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-140
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-140
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=11.901000
========= master_lag: 0.01 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 11 secs) (at 2016-09-28 02:41:49.557534) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-28 02:41:49.557883) =========
'bash' '-c' u'python archiver_client.py mozharness --repo integration/fx-team --rev 52cfd2babd94fa8452128940b50614cc12660785 --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 52cfd2babd94fa8452128940b50614cc12660785 --destination scripts --debug']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-140
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-140
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-140
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-28 02:41:49,793 truncating revision to first 12 chars
2016-09-28 02:41:49,793 Setting DEBUG logging.
2016-09-28 02:41:49,793 attempt 1/10
2016-09-28 02:41:49,793 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/52cfd2babd94?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2016-09-28 02:41:50,381 unpacking tar archive at: fx-team-52cfd2babd94/testing/mozharness/
2016-09-28 02:41:50,520 skipping symlink on windows: configs/single_locale/linux32.py
program finished with exit code 0
elapsedTime=1.097000
========= master_lag: 0.10 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-28 02:41:50.754770) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-28 02:41:50.755130) =========
script_repo_revision: 52cfd2babd94fa8452128940b50614cc12660785
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-28 02:41:50.755618) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-28 02:41:50.755903) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-28 02:41:50.792641) =========
========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 17 secs) (at 2016-09-28 02:41:50.793120) =========
'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Fx-Team-Non-PGO' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Fx-Team-Non-PGO'
in dir C:\slave\test\. (timeout 3600 secs) (maxTime 7200 secs)
watching logfiles {}
argv: ['c:/mozilla-build/python27/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team-Non-PGO', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-140
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-140
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-140
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
02:41:51 INFO - MultiFileLogger online at 20160928 02:41:51 in C:\slave\test
02:41:51 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO
02:41:51 INFO - Dumping config to C:\slave\test\logs\localconfig.json.
02:41:51 INFO - {'append_to_log': False,
02:41:51 INFO - 'base_work_dir': 'C:\\slave\\test',
02:41:51 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO',
02:41:51 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt',
02:41:51 INFO - 'branch': 'Fx-Team-Non-PGO',
02:41:51 INFO - 'buildbot_json_path': 'buildprops.json',
02:41:51 INFO - 'config_files': ('talos/windows_config.py',),
02:41:51 INFO - 'default_actions': ('clobber',
02:41:51 INFO - 'read-buildbot-config',
02:41:51 INFO - 'download-and-extract',
02:41:51 INFO - 'populate-webroot',
02:41:51 INFO - 'create-virtualenv',
02:41:51 INFO - 'install',
02:41:51 INFO - 'run-tests'),
02:41:51 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
02:41:51 INFO - 'download_minidump_stackwalk': True,
02:41:51 INFO - 'download_symbols': 'ondemand',
02:41:51 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python',
02:41:51 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'),
02:41:51 INFO - 'hg': 'c:/mozilla-build/hg/hg',
02:41:51 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python',
02:41:51 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'),
02:41:51 INFO - 'python': 'c:/mozilla-build/python27/python.exe',
02:41:51 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe',
02:41:51 INFO - 'C:/mozilla-build/tooltool.py'),
02:41:51 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe',
02:41:51 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')},
02:41:51 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
02:41:51 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
02:41:51 INFO - 'installer_path': 'installer.exe',
02:41:51 INFO - 'log_level': 'info',
02:41:51 INFO - 'log_name': 'talos',
02:41:51 INFO - 'log_to_console': True,
02:41:51 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe',
02:41:51 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe',
02:41:51 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest',
02:41:51 INFO - 'opt_config_files': (),
02:41:51 INFO - 'pip_index': False,
02:41:51 INFO - 'sps_profile': False,
02:41:51 INFO - 'sps_profile_interval': 0,
02:41:51 INFO - 'suite': 'dromaeojs',
02:41:51 INFO - 'system_bits': '32',
02:41:51 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
02:41:51 INFO - 'title': 'T-W732-IX-140',
02:41:51 INFO - 'use_talos_json': True,
02:41:51 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'),
02:41:51 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv',
02:41:51 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll',
02:41:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
02:41:51 INFO - 'work_dir': 'build'}
02:41:51 INFO - [mozharness: 2016-09-28 09:41:51.327000Z] Running clobber step.
02:41:51 INFO - Running pre-action listener: _resource_record_pre_action
02:41:51 INFO - Running main action method: clobber
02:41:51 INFO - rmtree: C:\slave\test\build
02:41:51 INFO - Using _rmtree_windows ...
02:41:51 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1
02:44:13 INFO - Running post-action listener: _resource_record_post_action
02:44:13 INFO - [mozharness: 2016-09-28 09:44:13.497000Z] Finished clobber step (success)
02:44:13 INFO - [mozharness: 2016-09-28 09:44:13.497000Z] Running read-buildbot-config step.
02:44:13 INFO - Running pre-action listener: _resource_record_pre_action
02:44:13 INFO - Running main action method: read_buildbot_config
02:44:13 INFO - Using buildbot properties:
02:44:13 INFO - {
02:44:13 INFO - "project": "",
02:44:13 INFO - "product": "firefox",
02:44:13 INFO - "script_repo_revision": "production",
02:44:13 INFO - "builddir": "fx-team_win7_ix_test-dromaeojs",
02:44:13 INFO - "repository": "",
02:44:13 INFO - "buildername": "Windows 7 32-bit fx-team talos dromaeojs",
02:44:13 INFO - "buildid": "20160928010920",
02:44:13 INFO - "pgo_build": "False",
02:44:13 INFO - "basedir": "C:\\slave\\test",
02:44:13 INFO - "buildnumber": 224,
02:44:13 INFO - "slavename": "t-w732-ix-140",
02:44:13 INFO - "master": "http://buildbot-master119.bb.releng.scl3.mozilla.com:8201/",
02:44:13 INFO - "platform": "win7_ix",
02:44:13 INFO - "scheduler": "tests-fx-team-win32-talos",
02:44:13 INFO - "branch": "fx-team",
02:44:13 INFO - "repo_path": "integration/fx-team",
02:44:13 INFO - "revision": "52cfd2babd94fa8452128940b50614cc12660785",
02:44:13 INFO - "stage_platform": "win32",
02:44:13 INFO - "builduid": "4c2b5161396b4448a18f710702628217",
02:44:13 INFO - "slavebuilddir": "test"
02:44:13 INFO - }
02:44:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/dFMBNftxRKKCcOFSw43h-g/artifacts/public/build/firefox-52.0a1.en-US.win32.zip.
02:44:13 INFO - Running post-action listener: _resource_record_post_action
02:44:13 INFO - [mozharness: 2016-09-28 09:44:13.501000Z] Finished read-buildbot-config step (success)
02:44:13 INFO - [mozharness: 2016-09-28 09:44:13.501000Z] Running download-and-extract step.
02:44:13 INFO - Running pre-action listener: _resource_record_pre_action
02:44:13 INFO - Running main action method: download_and_extract
02:44:13 INFO - mkdir: C:\slave\test\build\tests
02:44:13 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')]}
02:44:13 INFO - trying https://queue.taskcluster.net/v1/task/dFMBNftxRKKCcOFSw43h-g/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json
02:44:13 INFO - Downloading https://queue.taskcluster.net/v1/task/dFMBNftxRKKCcOFSw43h-g/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
02:44:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dFMBNftxRKKCcOFSw43h-g/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
02:44:14 INFO - Downloaded 1274 bytes.
02:44:14 INFO - Reading from file C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json
02:44:14 INFO - Using the following test package requirements:
02:44:14 INFO - {u'common': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
02:44:14 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
02:44:14 INFO - u'firefox-52.0a1.en-US.win32.cppunittest.tests.zip'],
02:44:14 INFO - u'gtest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
02:44:14 INFO - u'firefox-52.0a1.en-US.win32.gtest.tests.zip'],
02:44:14 INFO - u'jittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
02:44:14 INFO - u'jsshell-win32.zip'],
02:44:14 INFO - u'mochitest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
02:44:14 INFO - u'firefox-52.0a1.en-US.win32.mochitest.tests.zip'],
02:44:14 INFO - u'mozbase': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
02:44:14 INFO - u'reftest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
02:44:14 INFO - u'firefox-52.0a1.en-US.win32.reftest.tests.zip'],
02:44:14 INFO - u'talos': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
02:44:14 INFO - u'firefox-52.0a1.en-US.win32.talos.tests.zip'],
02:44:14 INFO - u'web-platform': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
02:44:14 INFO - u'firefox-52.0a1.en-US.win32.web-platform.tests.zip'],
02:44:14 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
02:44:14 INFO - u'firefox-52.0a1.en-US.win32.xpcshell.tests.zip']}
02:44:14 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip'] for test suite category: common
02:44:14 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/dFMBNftxRKKCcOFSw43h-g/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip
02:44:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dFMBNftxRKKCcOFSw43h-g/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1
02:44:14 INFO - Fetch https://queue.taskcluster.net/v1/task/dFMBNftxRKKCcOFSw43h-g/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory
02:44:15 INFO - Expected file size: 17322519
02:44:49 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
02:44:49 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/dFMBNftxRKKCcOFSw43h-g/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip
02:44:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dFMBNftxRKKCcOFSw43h-g/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1
02:44:49 INFO - Fetch https://queue.taskcluster.net/v1/task/dFMBNftxRKKCcOFSw43h-g/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory
02:44:50 INFO - Expected file size: 17322519
02:45:02 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/dFMBNftxRKKCcOFSw43h-g/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip
02:45:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dFMBNftxRKKCcOFSw43h-g/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip'}, attempt #1
02:45:02 INFO - Fetch https://queue.taskcluster.net/v1/task/dFMBNftxRKKCcOFSw43h-g/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip into memory
02:45:02 INFO - Expected file size: 14414062
02:45: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')]}
02:45:06 INFO - trying https://queue.taskcluster.net/v1/task/dFMBNftxRKKCcOFSw43h-g/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
02:45:06 INFO - Downloading https://queue.taskcluster.net/v1/task/dFMBNftxRKKCcOFSw43h-g/artifacts/public/build/firefox-52.0a1.en-US.win32.zip to C:\slave\test\build\firefox-52.0a1.en-US.win32.zip
02:45:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dFMBNftxRKKCcOFSw43h-g/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
02:45:08 INFO - Downloaded 53510677 bytes.
02:45:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dFMBNftxRKKCcOFSw43h-g/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
02:45:08 INFO - mkdir: C:\slave\test\properties
02:45:08 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url
02:45:08 INFO - Writing to file C:\slave\test\properties\build_url
02:45:08 INFO - Contents:
02:45:08 INFO - build_url:https://queue.taskcluster.net/v1/task/dFMBNftxRKKCcOFSw43h-g/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
02:45:08 INFO - Running post-action listener: _resource_record_post_action
02:45:08 INFO - Running post-action listener: set_extra_try_arguments
02:45:08 INFO - [mozharness: 2016-09-28 09:45:08.971000Z] Finished download-and-extract step (success)
02:45:08 INFO - [mozharness: 2016-09-28 09:45:08.972000Z] Running populate-webroot step.
02:45:08 INFO - Running pre-action listener: _resource_record_pre_action
02:45:08 INFO - Running main action method: populate_webroot
02:45:08 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']},
02:45:08 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']},
02:45:08 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome',
02:45:08 INFO - u'--tppagecycles',
02:45:08 INFO - u'7'],
02:45:08 INFO - u'tests': [u'tsvgm']}},
02:45:08 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'],
02:45:08 INFO - u'tests': [u'tresize', u'tcanvasmark']},
02:45:08 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']},
02:45:08 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'],
02:45:08 INFO - u'tests': [u'dromaeo_css', u'kraken']},
02:45:08 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']},
02:45:08 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
02:45:08 INFO - u'talos_options': [u'--disable-e10s'],
02:45:08 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
02:45:08 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
02:45:08 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
02:45:08 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
02:45:08 INFO - u'talos_options': [u'--disable-e10s'],
02:45:08 INFO - u'tests': [u'damp', u'tps']},
02:45:08 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
02:45:08 INFO - u'tests': [u'damp', u'tps']},
02:45:08 INFO - u'g3': {u'talos_options': [u'--disable-e10s'],
02:45:08 INFO - u'tests': [u'dromaeo_dom']},
02:45:08 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']},
02:45:08 INFO - u'g4': {u'talos_options': [u'--disable-e10s'],
02:45:08 INFO - u'tests': [u'basic_compositor_video']},
02:45:08 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']},
02:45:08 INFO - u'other': {u'talos_options': [u'--disable-e10s'],
02:45:08 INFO - u'tests': [u'a11yr',
02:45:08 INFO - u'ts_paint',
02:45:08 INFO - u'tpaint',
02:45:08 INFO - u'sessionrestore',
02:45:08 INFO - u'sessionrestore_no_auto_restore',
02:45:08 INFO - u'tabpaint']},
02:45:08 INFO - u'other-e10s': {u'tests': [u'a11yr',
02:45:08 INFO - u'ts_paint',
02:45:08 INFO - u'tpaint',
02:45:08 INFO - u'sessionrestore',
02:45:08 INFO - u'sessionrestore_no_auto_restore',
02:45:08 INFO - u'tabpaint']},
02:45:08 INFO - u'other-e10s_l64': {u'tests': [u'a11yr',
02:45:08 INFO - u'ts_paint',
02:45:08 INFO - u'tpaint',
02:45:08 INFO - u'sessionrestore',
02:45:08 INFO - u'sessionrestore_no_auto_restore',
02:45:08 INFO - u'tabpaint']},
02:45:08 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr',
02:45:08 INFO - u'ts_paint',
02:45:08 INFO - u'tpaint',
02:45:08 INFO - u'sessionrestore',
02:45:08 INFO - u'sessionrestore_no_auto_restore',
02:45:08 INFO - u'tabpaint']},
02:45:08 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'],
02:45:08 INFO - u'tests': [u'a11yr',
02:45:08 INFO - u'ts_paint',
02:45:08 INFO - u'tpaint',
02:45:08 INFO - u'sessionrestore',
02:45:08 INFO - u'sessionrestore_no_auto_restore',
02:45:08 INFO - u'tabpaint']},
02:45:08 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'],
02:45:08 INFO - u'tests': [u'a11yr',
02:45:08 INFO - u'ts_paint',
02:45:08 INFO - u'tpaint',
02:45:08 INFO - u'sessionrestore',
02:45:08 INFO - u'sessionrestore_no_auto_restore',
02:45:08 INFO - u'tabpaint']},
02:45:08 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'],
02:45:08 INFO - u'tests': [u'tsvgx',
02:45:08 INFO - u'tsvgr_opacity',
02:45:08 INFO - u'tart',
02:45:08 INFO - u'tscrollx',
02:45:08 INFO - u'cart']},
02:45:08 INFO - u'svgr-e10s': {u'tests': [u'tsvgx',
02:45:08 INFO - u'tsvgr_opacity',
02:45:08 INFO - u'tart',
02:45:08 INFO - u'tscrollx',
02:45:08 INFO - u'cart']},
02:45:08 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
02:45:08 INFO - u'talos_options': [u'--disable-e10s'],
02:45:08 INFO - u'tests': [u'tp5o']},
02:45:08 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
02:45:08 INFO - u'tests': [u'tp5o']},
02:45:08 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
02:45:08 INFO - u'talos_options': [u'--disable-e10s',
02:45:08 INFO - u'--xperf_path',
02:45:08 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
02:45:08 INFO - u'tests': [u'tp5n']},
02:45:08 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
02:45:08 INFO - u'talos_options': [u'--xperf_path',
02:45:08 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
02:45:08 INFO - u'tests': [u'tp5n']}},
02:45:08 INFO - u'talos.zip': {u'path': u'',
02:45:08 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}}
02:45:08 INFO - Running post-action listener: _resource_record_post_action
02:45:08 INFO - [mozharness: 2016-09-28 09:45:08.990000Z] Finished populate-webroot step (success)
02:45:08 INFO - [mozharness: 2016-09-28 09:45:08.991000Z] Running create-virtualenv step.
02:45:08 INFO - Running pre-action listener: _resource_record_pre_action
02:45:08 INFO - Running main action method: create_virtualenv
02:45:08 INFO - Creating virtualenv C:\slave\test\build/venv
02:45:08 INFO - mkdir: C:\slave\test\build\venv\Scripts
02:45:09 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll
02:45:09 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'!
02:45:09 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
02:45:09 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv
02:45:12 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe
02:45:22 INFO - Installing distribute......................................................................................................................................................................................done.
02:45:23 INFO - Return code: 0
02:45:23 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version']
02:45:23 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version
02:45:23 INFO - Reading from file tmpfile_stdout
02:45:23 INFO - Output received:
02:45:23 INFO - pip 0.8.2 from c:\slave\test\build\venv\lib\site-packages\pip-0.8.2-py2.7.egg (python 2.7)
02:45:23 INFO - Using _rmtree_windows ...
02:45:23 INFO - Using _rmtree_windows ...
02:45:23 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv
02:45:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:45:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:45:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:45:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:45:23 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01820640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018CBE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017CB228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018E8E90>, '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 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01E98520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-140', 'USERDOMAIN': 'T-W732-IX-140', '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-140', '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
02:45:23 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in C:\slave\test\build
02:45:23 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5
02:45:23 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
02:45:23 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
02:45:23 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
02:45:23 INFO - 'COMPUTERNAME': 'T-W732-IX-140',
02:45:23 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
02:45:23 INFO - 'CYGWINBASE': 'C:\\cygwin',
02:45:23 INFO - 'DCLOCATION': 'SCL3',
02:45:23 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
02:45:23 INFO - 'FP_NO_HOST_CHECK': 'NO',
02:45:23 INFO - 'HOMEDRIVE': 'C:',
02:45:23 INFO - 'HOMEPATH': '\\Users\\cltbld',
02:45:23 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
02:45:23 INFO - 'KTS_VERSION': '1.19c',
02:45:23 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
02:45:23 INFO - 'LOGONSERVER': '\\\\T-W732-IX-140',
02:45:23 INFO - 'MONDIR': 'C:\\Monitor_config\\',
02:45:23 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
02:45:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:45:23 INFO - 'MOZ_NO_REMOTE': '1',
02:45:23 INFO - 'NO_EM_RESTART': '1',
02:45:23 INFO - 'NUMBER_OF_PROCESSORS': '8',
02:45:23 INFO - 'OS': 'Windows_NT',
02:45:23 INFO - 'OURDRIVE': 'C:',
02:45:23 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
02:45:23 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
02:45:23 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
02:45:23 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
02:45:23 INFO - 'PROCESSOR_LEVEL': '6',
02:45:23 INFO - 'PROCESSOR_REVISION': '1e05',
02:45:23 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
02:45:23 INFO - 'PROGRAMFILES': 'C:\\Program Files',
02:45:23 INFO - 'PROMPT': '$P$G',
02:45:23 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
02:45:23 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
02:45:23 INFO - 'PUBLIC': 'C:\\Users\\Public',
02:45:23 INFO - 'PWD': 'C:\\slave\\test',
02:45:23 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
02:45:23 INFO - 'SLAVEDIR': 'C:\\slave\\',
02:45:23 INFO - 'SYSTEMDRIVE': 'C:',
02:45:23 INFO - 'SYSTEMROOT': 'C:\\windows',
02:45:23 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
02:45:23 INFO - 'TEST1': 'testie',
02:45:23 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
02:45:23 INFO - 'USERDOMAIN': 'T-W732-IX-140',
02:45:23 INFO - 'USERNAME': 'cltbld',
02:45:23 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
02:45:23 INFO - 'WINDIR': 'C:\\windows',
02:45:23 INFO - 'WINDOWS_TRACING_FLAGS': '3',
02:45:23 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
02:45:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
02:45:24 INFO - Ignoring indexes: http://pypi.python.org/simple/
02:45:24 INFO - Downloading/unpacking pip>=1.5
02:45:29 INFO - Running setup.py egg_info for package pip
02:45:29 INFO - warning: no files found matching 'pip\cacert.pem'
02:45:29 INFO - warning: no files found matching '*.html' under directory 'docs'
02:45:29 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
02:45:29 INFO - no previously-included directories found matching 'docs\_build\_sources'
02:45:29 INFO - Installing collected packages: pip
02:45:29 INFO - Found existing installation: pip 0.8.2
02:45:29 INFO - Uninstalling pip:
02:45:29 INFO - Successfully uninstalled pip
02:45:29 INFO - Running setup.py install for pip
02:45:29 INFO - warning: no files found matching 'pip\cacert.pem'
02:45:29 INFO - warning: no files found matching '*.html' under directory 'docs'
02:45:29 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
02:45:29 INFO - no previously-included directories found matching 'docs\_build\_sources'
02:45:29 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts
02:45:29 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts
02:45:29 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts
02:45:29 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts
02:45:29 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts
02:45:29 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts
02:45:29 INFO - Successfully installed pip
02:45:29 INFO - Cleaning up...
02:45:29 INFO - Return code: 0
02:45:29 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv
02:45:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:45:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:45:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:45:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:45:29 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x01820640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018CBE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017CB228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018E8E90>, '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 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01E98520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-140', 'USERDOMAIN': 'T-W732-IX-140', '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-140', '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
02:45:29 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in C:\slave\test\build
02:45:29 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1
02:45:29 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
02:45:29 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
02:45:29 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
02:45:29 INFO - 'COMPUTERNAME': 'T-W732-IX-140',
02:45:29 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
02:45:29 INFO - 'CYGWINBASE': 'C:\\cygwin',
02:45:29 INFO - 'DCLOCATION': 'SCL3',
02:45:29 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
02:45:29 INFO - 'FP_NO_HOST_CHECK': 'NO',
02:45:29 INFO - 'HOMEDRIVE': 'C:',
02:45:29 INFO - 'HOMEPATH': '\\Users\\cltbld',
02:45:29 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
02:45:29 INFO - 'KTS_VERSION': '1.19c',
02:45:29 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
02:45:29 INFO - 'LOGONSERVER': '\\\\T-W732-IX-140',
02:45:29 INFO - 'MONDIR': 'C:\\Monitor_config\\',
02:45:29 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
02:45:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:45:29 INFO - 'MOZ_NO_REMOTE': '1',
02:45:29 INFO - 'NO_EM_RESTART': '1',
02:45:29 INFO - 'NUMBER_OF_PROCESSORS': '8',
02:45:29 INFO - 'OS': 'Windows_NT',
02:45:29 INFO - 'OURDRIVE': 'C:',
02:45:29 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
02:45:29 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
02:45:29 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
02:45:29 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
02:45:29 INFO - 'PROCESSOR_LEVEL': '6',
02:45:29 INFO - 'PROCESSOR_REVISION': '1e05',
02:45:29 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
02:45:29 INFO - 'PROGRAMFILES': 'C:\\Program Files',
02:45:29 INFO - 'PROMPT': '$P$G',
02:45:29 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
02:45:29 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
02:45:29 INFO - 'PUBLIC': 'C:\\Users\\Public',
02:45:29 INFO - 'PWD': 'C:\\slave\\test',
02:45:29 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
02:45:29 INFO - 'SLAVEDIR': 'C:\\slave\\',
02:45:29 INFO - 'SYSTEMDRIVE': 'C:',
02:45:29 INFO - 'SYSTEMROOT': 'C:\\windows',
02:45:29 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
02:45:29 INFO - 'TEST1': 'testie',
02:45:29 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
02:45:29 INFO - 'USERDOMAIN': 'T-W732-IX-140',
02:45:29 INFO - 'USERNAME': 'cltbld',
02:45:29 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
02:45:29 INFO - 'WINDIR': 'C:\\windows',
02:45:29 INFO - 'WINDOWS_TRACING_FLAGS': '3',
02:45:29 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
02:45:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
02:45:30 INFO - Ignoring indexes: https://pypi.python.org/simple/
02:45:30 INFO - Downloading/unpacking psutil>=3.1.1
02:45:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
02:45:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
02:45:31 INFO - Installing collected packages: psutil
02:45:31 INFO - Successfully installed psutil
02:45:31 INFO - Cleaning up...
02:45:31 INFO - Return code: 0
02:45:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv
02:45:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:45:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:45:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:45:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:45:31 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01820640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018CBE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017CB228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018E8E90>, '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 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01E98520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-140', 'USERDOMAIN': 'T-W732-IX-140', '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-140', '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
02:45:31 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in C:\slave\test\build
02:45:31 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3
02:45:31 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
02:45:31 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
02:45:31 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
02:45:31 INFO - 'COMPUTERNAME': 'T-W732-IX-140',
02:45:31 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
02:45:31 INFO - 'CYGWINBASE': 'C:\\cygwin',
02:45:31 INFO - 'DCLOCATION': 'SCL3',
02:45:31 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
02:45:31 INFO - 'FP_NO_HOST_CHECK': 'NO',
02:45:31 INFO - 'HOMEDRIVE': 'C:',
02:45:31 INFO - 'HOMEPATH': '\\Users\\cltbld',
02:45:31 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
02:45:31 INFO - 'KTS_VERSION': '1.19c',
02:45:31 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
02:45:31 INFO - 'LOGONSERVER': '\\\\T-W732-IX-140',
02:45:31 INFO - 'MONDIR': 'C:\\Monitor_config\\',
02:45:31 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
02:45:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:45:31 INFO - 'MOZ_NO_REMOTE': '1',
02:45:31 INFO - 'NO_EM_RESTART': '1',
02:45:31 INFO - 'NUMBER_OF_PROCESSORS': '8',
02:45:31 INFO - 'OS': 'Windows_NT',
02:45:31 INFO - 'OURDRIVE': 'C:',
02:45:31 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
02:45:31 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
02:45:31 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
02:45:31 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
02:45:31 INFO - 'PROCESSOR_LEVEL': '6',
02:45:31 INFO - 'PROCESSOR_REVISION': '1e05',
02:45:31 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
02:45:31 INFO - 'PROGRAMFILES': 'C:\\Program Files',
02:45:31 INFO - 'PROMPT': '$P$G',
02:45:31 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
02:45:31 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
02:45:31 INFO - 'PUBLIC': 'C:\\Users\\Public',
02:45:31 INFO - 'PWD': 'C:\\slave\\test',
02:45:31 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
02:45:31 INFO - 'SLAVEDIR': 'C:\\slave\\',
02:45:31 INFO - 'SYSTEMDRIVE': 'C:',
02:45:31 INFO - 'SYSTEMROOT': 'C:\\windows',
02:45:31 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
02:45:31 INFO - 'TEST1': 'testie',
02:45:31 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
02:45:31 INFO - 'USERDOMAIN': 'T-W732-IX-140',
02:45:31 INFO - 'USERNAME': 'cltbld',
02:45:31 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
02:45:31 INFO - 'WINDIR': 'C:\\windows',
02:45:31 INFO - 'WINDOWS_TRACING_FLAGS': '3',
02:45:31 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
02:45:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
02:45:32 INFO - Ignoring indexes: https://pypi.python.org/simple/
02:45:32 INFO - Downloading/unpacking mozsystemmonitor==0.3
02:45:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
02:45:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
02:45:33 INFO - Downloading mozsystemmonitor-0.3.tar.gz
02:45:33 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor
02:45:33 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)
02:45:33 INFO - Installing collected packages: mozsystemmonitor
02:45:33 INFO - Running setup.py install for mozsystemmonitor
02:45:33 INFO - Successfully installed mozsystemmonitor
02:45:33 INFO - Cleaning up...
02:45:33 INFO - Return code: 0
02:45:33 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv
02:45:33 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')]}
02:45:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:45:33 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')]}
02:45:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:45:33 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 0x01820640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018CBE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017CB228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018E8E90>, '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 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01E98520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-140', 'USERDOMAIN': 'T-W732-IX-140', '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-140', '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
02:45:33 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
02:45:33 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
02:45:33 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
02:45:33 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
02:45:33 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
02:45:33 INFO - 'COMPUTERNAME': 'T-W732-IX-140',
02:45:33 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
02:45:33 INFO - 'CYGWINBASE': 'C:\\cygwin',
02:45:33 INFO - 'DCLOCATION': 'SCL3',
02:45:33 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
02:45:33 INFO - 'FP_NO_HOST_CHECK': 'NO',
02:45:33 INFO - 'HOMEDRIVE': 'C:',
02:45:33 INFO - 'HOMEPATH': '\\Users\\cltbld',
02:45:33 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
02:45:33 INFO - 'KTS_VERSION': '1.19c',
02:45:33 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
02:45:33 INFO - 'LOGONSERVER': '\\\\T-W732-IX-140',
02:45:33 INFO - 'MONDIR': 'C:\\Monitor_config\\',
02:45:33 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
02:45:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:45:33 INFO - 'MOZ_NO_REMOTE': '1',
02:45:33 INFO - 'NO_EM_RESTART': '1',
02:45:33 INFO - 'NUMBER_OF_PROCESSORS': '8',
02:45:33 INFO - 'OS': 'Windows_NT',
02:45:33 INFO - 'OURDRIVE': 'C:',
02:45:33 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;',
02:45:33 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
02:45:33 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
02:45:33 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
02:45:33 INFO - 'PROCESSOR_LEVEL': '6',
02:45:33 INFO - 'PROCESSOR_REVISION': '1e05',
02:45:33 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
02:45:33 INFO - 'PROGRAMFILES': 'C:\\Program Files',
02:45:33 INFO - 'PROMPT': '$P$G',
02:45:33 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
02:45:33 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
02:45:33 INFO - 'PUBLIC': 'C:\\Users\\Public',
02:45:33 INFO - 'PWD': 'C:\\slave\\test',
02:45:33 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
02:45:33 INFO - 'SLAVEDIR': 'C:\\slave\\',
02:45:33 INFO - 'SYSTEMDRIVE': 'C:',
02:45:33 INFO - 'SYSTEMROOT': 'C:\\windows',
02:45:33 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
02:45:33 INFO - 'TEST1': 'testie',
02:45:33 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
02:45:33 INFO - 'USERDOMAIN': 'T-W732-IX-140',
02:45:33 INFO - 'USERNAME': 'cltbld',
02:45:33 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
02:45:33 INFO - 'WINDIR': 'C:\\windows',
02:45:33 INFO - 'WINDOWS_TRACING_FLAGS': '3',
02:45:33 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
02:45:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
02:45:34 INFO - Ignoring indexes: https://pypi.python.org/simple/
02:45:34 INFO - Downloading/unpacking jsonschema==2.5.1
02:45:34 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
02:45:34 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
02:45:35 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl
02:45:35 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1)
02:45:35 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
02:45:35 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
02:45:35 INFO - Downloading functools32-3.2.3-2.tar.gz
02:45:35 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32
02:45:35 INFO - warning: no files found matching '*.txt'
02:45:35 INFO - no previously-included directories found matching 'build'
02:45:35 INFO - no previously-included directories found matching 'dist'
02:45:35 INFO - no previously-included directories found matching '.git*'
02:45:35 INFO - Installing collected packages: jsonschema, functools32
02:45:35 INFO - Running setup.py install for functools32
02:45:35 INFO - warning: no files found matching '*.txt'
02:45:35 INFO - no previously-included directories found matching 'build'
02:45:35 INFO - no previously-included directories found matching 'dist'
02:45:35 INFO - no previously-included directories found matching '.git*'
02:45:35 INFO - Successfully installed jsonschema functools32
02:45:35 INFO - Cleaning up...
02:45:36 INFO - Return code: 0
02:45:36 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv
02:45:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:45:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:45:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:45:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:45:36 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 0x01820640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018CBE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017CB228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018E8E90>, '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 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01E98520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-140', 'USERDOMAIN': 'T-W732-IX-140', '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-140', '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
02:45:36 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
02:45:36 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
02:45:36 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
02:45:36 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
02:45:36 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
02:45:36 INFO - 'COMPUTERNAME': 'T-W732-IX-140',
02:45:36 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
02:45:36 INFO - 'CYGWINBASE': 'C:\\cygwin',
02:45:36 INFO - 'DCLOCATION': 'SCL3',
02:45:36 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
02:45:36 INFO - 'FP_NO_HOST_CHECK': 'NO',
02:45:36 INFO - 'HOMEDRIVE': 'C:',
02:45:36 INFO - 'HOMEPATH': '\\Users\\cltbld',
02:45:36 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
02:45:36 INFO - 'KTS_VERSION': '1.19c',
02:45:36 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
02:45:36 INFO - 'LOGONSERVER': '\\\\T-W732-IX-140',
02:45:36 INFO - 'MONDIR': 'C:\\Monitor_config\\',
02:45:36 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
02:45:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:45:36 INFO - 'MOZ_NO_REMOTE': '1',
02:45:36 INFO - 'NO_EM_RESTART': '1',
02:45:36 INFO - 'NUMBER_OF_PROCESSORS': '8',
02:45:36 INFO - 'OS': 'Windows_NT',
02:45:36 INFO - 'OURDRIVE': 'C:',
02:45:36 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
02:45:36 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
02:45:36 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
02:45:36 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
02:45:36 INFO - 'PROCESSOR_LEVEL': '6',
02:45:36 INFO - 'PROCESSOR_REVISION': '1e05',
02:45:36 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
02:45:36 INFO - 'PROGRAMFILES': 'C:\\Program Files',
02:45:36 INFO - 'PROMPT': '$P$G',
02:45:36 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
02:45:36 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
02:45:36 INFO - 'PUBLIC': 'C:\\Users\\Public',
02:45:36 INFO - 'PWD': 'C:\\slave\\test',
02:45:36 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
02:45:36 INFO - 'SLAVEDIR': 'C:\\slave\\',
02:45:36 INFO - 'SYSTEMDRIVE': 'C:',
02:45:36 INFO - 'SYSTEMROOT': 'C:\\windows',
02:45:36 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
02:45:36 INFO - 'TEST1': 'testie',
02:45:36 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
02:45:36 INFO - 'USERDOMAIN': 'T-W732-IX-140',
02:45:36 INFO - 'USERNAME': 'cltbld',
02:45:36 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
02:45:36 INFO - 'WINDIR': 'C:\\windows',
02:45:36 INFO - 'WINDOWS_TRACING_FLAGS': '3',
02:45:36 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
02:45:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
02:45:36 INFO - Ignoring indexes: https://pypi.python.org/simple/
02:45:36 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages
02:45:36 INFO - Cleaning up...
02:45:36 INFO - Return code: 0
02:45:36 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv
02:45:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:45:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:45:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:45:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:45:36 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 0x01820640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018CBE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017CB228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018E8E90>, '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 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01E98520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-140', 'USERDOMAIN': 'T-W732-IX-140', '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-140', '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
02:45:36 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
02:45:36 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
02:45:36 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
02:45:36 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
02:45:36 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
02:45:36 INFO - 'COMPUTERNAME': 'T-W732-IX-140',
02:45:36 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
02:45:36 INFO - 'CYGWINBASE': 'C:\\cygwin',
02:45:36 INFO - 'DCLOCATION': 'SCL3',
02:45:36 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
02:45:36 INFO - 'FP_NO_HOST_CHECK': 'NO',
02:45:36 INFO - 'HOMEDRIVE': 'C:',
02:45:36 INFO - 'HOMEPATH': '\\Users\\cltbld',
02:45:36 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
02:45:36 INFO - 'KTS_VERSION': '1.19c',
02:45:36 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
02:45:36 INFO - 'LOGONSERVER': '\\\\T-W732-IX-140',
02:45:36 INFO - 'MONDIR': 'C:\\Monitor_config\\',
02:45:36 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
02:45:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:45:36 INFO - 'MOZ_NO_REMOTE': '1',
02:45:36 INFO - 'NO_EM_RESTART': '1',
02:45:36 INFO - 'NUMBER_OF_PROCESSORS': '8',
02:45:36 INFO - 'OS': 'Windows_NT',
02:45:36 INFO - 'OURDRIVE': 'C:',
02:45:36 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
02:45:36 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
02:45:36 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
02:45:36 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
02:45:36 INFO - 'PROCESSOR_LEVEL': '6',
02:45:36 INFO - 'PROCESSOR_REVISION': '1e05',
02:45:36 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
02:45:36 INFO - 'PROGRAMFILES': 'C:\\Program Files',
02:45:36 INFO - 'PROMPT': '$P$G',
02:45:36 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
02:45:36 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
02:45:36 INFO - 'PUBLIC': 'C:\\Users\\Public',
02:45:36 INFO - 'PWD': 'C:\\slave\\test',
02:45:36 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
02:45:36 INFO - 'SLAVEDIR': 'C:\\slave\\',
02:45:36 INFO - 'SYSTEMDRIVE': 'C:',
02:45:36 INFO - 'SYSTEMROOT': 'C:\\windows',
02:45:36 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
02:45:36 INFO - 'TEST1': 'testie',
02:45:36 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
02:45:36 INFO - 'USERDOMAIN': 'T-W732-IX-140',
02:45:36 INFO - 'USERNAME': 'cltbld',
02:45:36 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
02:45:36 INFO - 'WINDIR': 'C:\\windows',
02:45:36 INFO - 'WINDOWS_TRACING_FLAGS': '3',
02:45:36 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
02:45:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
02:45:36 INFO - Ignoring indexes: https://pypi.python.org/simple/
02:45:36 INFO - Downloading/unpacking blobuploader==1.2.4
02:45:36 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
02:45:36 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
02:45:37 INFO - Downloading blobuploader-1.2.4.tar.gz
02:45:37 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader
02:45:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4)
02:45:37 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
02:45:37 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
02:45:39 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests
02:45:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4)
02:45:39 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
02:45:39 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
02:45:39 INFO - Downloading docopt-0.6.1.tar.gz
02:45:39 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt
02:45:39 INFO - Installing collected packages: blobuploader, requests, docopt
02:45:39 INFO - Running setup.py install for blobuploader
02:45:39 INFO - Running setup.py install for requests
02:45:40 INFO - Running setup.py install for docopt
02:45:40 INFO - Successfully installed blobuploader requests docopt
02:45:40 INFO - Cleaning up...
02:45:41 INFO - Return code: 0
02:45:41 INFO - Installing None into virtualenv C:\slave\test\build/venv
02:45: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')]}
02:45:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:45: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')]}
02:45:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:45:41 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 0x01820640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018CBE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017CB228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018E8E90>, '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 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01E98520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-140', 'USERDOMAIN': 'T-W732-IX-140', '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-140', '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
02:45:41 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
02:45:41 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
02:45:41 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
02:45:41 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
02:45:41 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
02:45:41 INFO - 'COMPUTERNAME': 'T-W732-IX-140',
02:45:41 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
02:45:41 INFO - 'CYGWINBASE': 'C:\\cygwin',
02:45:41 INFO - 'DCLOCATION': 'SCL3',
02:45:41 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
02:45:41 INFO - 'FP_NO_HOST_CHECK': 'NO',
02:45:41 INFO - 'HOMEDRIVE': 'C:',
02:45:41 INFO - 'HOMEPATH': '\\Users\\cltbld',
02:45:41 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
02:45:41 INFO - 'KTS_VERSION': '1.19c',
02:45:41 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
02:45:41 INFO - 'LOGONSERVER': '\\\\T-W732-IX-140',
02:45:41 INFO - 'MONDIR': 'C:\\Monitor_config\\',
02:45:41 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
02:45:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:45:41 INFO - 'MOZ_NO_REMOTE': '1',
02:45:41 INFO - 'NO_EM_RESTART': '1',
02:45:41 INFO - 'NUMBER_OF_PROCESSORS': '8',
02:45:41 INFO - 'OS': 'Windows_NT',
02:45:41 INFO - 'OURDRIVE': 'C:',
02:45:41 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;',
02:45:41 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
02:45:41 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
02:45:41 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
02:45:41 INFO - 'PROCESSOR_LEVEL': '6',
02:45:41 INFO - 'PROCESSOR_REVISION': '1e05',
02:45:41 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
02:45:41 INFO - 'PROGRAMFILES': 'C:\\Program Files',
02:45:41 INFO - 'PROMPT': '$P$G',
02:45:41 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
02:45:41 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
02:45:41 INFO - 'PUBLIC': 'C:\\Users\\Public',
02:45:41 INFO - 'PWD': 'C:\\slave\\test',
02:45:41 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
02:45:41 INFO - 'SLAVEDIR': 'C:\\slave\\',
02:45:41 INFO - 'SYSTEMDRIVE': 'C:',
02:45:41 INFO - 'SYSTEMROOT': 'C:\\windows',
02:45:41 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
02:45:41 INFO - 'TEST1': 'testie',
02:45:41 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
02:45:41 INFO - 'USERDOMAIN': 'T-W732-IX-140',
02:45:41 INFO - 'USERNAME': 'cltbld',
02:45:41 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
02:45:41 INFO - 'WINDIR': 'C:\\windows',
02:45:41 INFO - 'WINDOWS_TRACING_FLAGS': '3',
02:45:41 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
02:45:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
02:45:42 INFO - Ignoring indexes: https://pypi.python.org/simple/
02:45:42 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
02:45:42 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mbl36d-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
02:45:42 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
02:45:42 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rsg38d-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
02:45:42 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
02:45:42 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pogv6z-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
02:45:42 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
02:45:42 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-7zqq6c-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
02:45:42 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
02:45:42 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-syx3zm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
02:45:42 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
02:45:42 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vybdmk-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
02:45:42 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
02:45:42 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-h1vs9a-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
02:45:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
02:45:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fgngdy-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
02:45:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
02:45:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-c5anxz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
02:45:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
02:45:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jlzpep-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
02:45:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
02:45:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-7b4r2t-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
02:45:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
02:45:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-c5qigd-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
02:45:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
02:45:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-nbcxj_-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
02:45:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
02:45:44 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-q4qycq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
02:45:44 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
02:45:44 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cl55yu-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
02:45:45 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
02:45:45 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-7qbsxk-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
02:45:45 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
02:45:45 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hsnekx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
02:45:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
02:45:45 INFO - Running setup.py install for manifestparser
02:45:45 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts
02:45:45 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts
02:45:45 INFO - Running setup.py install for mozcrash
02:45:46 INFO - Running setup.py install for mozdebug
02:45:46 INFO - Running setup.py install for mozdevice
02:45:46 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts
02:45:46 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts
02:45:46 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts
02:45:46 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts
02:45:47 INFO - Running setup.py install for mozfile
02:45:47 INFO - Running setup.py install for mozhttpd
02:45:47 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts
02:45:47 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts
02:45:48 INFO - Running setup.py install for mozinfo
02:45:48 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts
02:45:48 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts
02:45:48 INFO - Running setup.py install for mozInstall
02:45:48 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts
02:45:48 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts
02:45:48 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts
02:45:48 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts
02:45:48 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts
02:45:48 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts
02:45:48 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts
02:45:48 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts
02:45:48 INFO - Running setup.py install for mozleak
02:45:49 INFO - Running setup.py install for mozlog
02:45:49 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts
02:45:49 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts
02:45:49 INFO - Running setup.py install for moznetwork
02:45:50 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts
02:45:50 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts
02:45:50 INFO - Running setup.py install for mozprocess
02:45:50 INFO - Running setup.py install for mozprofile
02:45:51 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts
02:45:51 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts
02:45:51 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts
02:45:51 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts
02:45:51 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts
02:45:51 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts
02:45:51 INFO - Running setup.py install for mozrunner
02:45:51 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts
02:45:51 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts
02:45:52 INFO - Running setup.py install for mozscreenshot
02:45:52 INFO - Running setup.py install for moztest
02:45:52 INFO - Running setup.py install for mozversion
02:45:53 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts
02:45:53 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts
02:45:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion
02:45:53 INFO - Cleaning up...
02:45:53 INFO - Return code: 0
02:45:53 INFO - Installing None into virtualenv C:\slave\test\build/venv
02:45:53 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')]}
02:45:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:45:53 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')]}
02:45:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:45:53 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 0x01820640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018CBE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017CB228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018E8E90>, '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 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01E98520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-140', 'USERDOMAIN': 'T-W732-IX-140', '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-140', '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
02:45:53 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
02:45:53 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
02:45:53 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
02:45:53 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
02:45:53 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
02:45:53 INFO - 'COMPUTERNAME': 'T-W732-IX-140',
02:45:53 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
02:45:53 INFO - 'CYGWINBASE': 'C:\\cygwin',
02:45:53 INFO - 'DCLOCATION': 'SCL3',
02:45:53 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
02:45:53 INFO - 'FP_NO_HOST_CHECK': 'NO',
02:45:53 INFO - 'HOMEDRIVE': 'C:',
02:45:53 INFO - 'HOMEPATH': '\\Users\\cltbld',
02:45:53 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
02:45:53 INFO - 'KTS_VERSION': '1.19c',
02:45:53 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
02:45:53 INFO - 'LOGONSERVER': '\\\\T-W732-IX-140',
02:45:53 INFO - 'MONDIR': 'C:\\Monitor_config\\',
02:45:53 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
02:45:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:45:53 INFO - 'MOZ_NO_REMOTE': '1',
02:45:53 INFO - 'NO_EM_RESTART': '1',
02:45:53 INFO - 'NUMBER_OF_PROCESSORS': '8',
02:45:53 INFO - 'OS': 'Windows_NT',
02:45:53 INFO - 'OURDRIVE': 'C:',
02:45:53 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;',
02:45:53 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
02:45:53 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
02:45:53 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
02:45:53 INFO - 'PROCESSOR_LEVEL': '6',
02:45:53 INFO - 'PROCESSOR_REVISION': '1e05',
02:45:53 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
02:45:53 INFO - 'PROGRAMFILES': 'C:\\Program Files',
02:45:53 INFO - 'PROMPT': '$P$G',
02:45:53 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
02:45:53 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
02:45:53 INFO - 'PUBLIC': 'C:\\Users\\Public',
02:45:53 INFO - 'PWD': 'C:\\slave\\test',
02:45:53 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
02:45:53 INFO - 'SLAVEDIR': 'C:\\slave\\',
02:45:53 INFO - 'SYSTEMDRIVE': 'C:',
02:45:53 INFO - 'SYSTEMROOT': 'C:\\windows',
02:45:53 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
02:45:53 INFO - 'TEST1': 'testie',
02:45:53 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
02:45:53 INFO - 'USERDOMAIN': 'T-W732-IX-140',
02:45:53 INFO - 'USERNAME': 'cltbld',
02:45:53 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
02:45:53 INFO - 'WINDIR': 'C:\\windows',
02:45:53 INFO - 'WINDOWS_TRACING_FLAGS': '3',
02:45:53 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
02:45:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
02:45:53 INFO - Ignoring indexes: https://pypi.python.org/simple/
02:45:53 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
02:45:53 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-_hslrb-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
02:45:53 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))
02:45:53 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
02:45:53 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-1v54vp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
02:45:53 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))
02:45:53 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
02:45:53 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wd5uqi-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
02:45:54 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))
02:45:54 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
02:45:54 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pwjxah-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
02:45:54 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))
02:45:54 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
02:45:54 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pqzaox-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
02:45:54 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))
02:45:54 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
02:45:54 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-10mh58-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
02:45:54 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))
02:45:54 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
02:45:54 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ds25vz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
02:45:55 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))
02:45:55 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
02:45:55 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-h1dm2w-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
02:45:55 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))
02:45:55 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
02:45:55 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-n9gmb1-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
02:45:55 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))
02:45:55 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
02:45:55 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-nmloy2-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
02:45:55 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))
02:45:55 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
02:45:55 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-2a5emx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
02:45:55 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))
02:45:55 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
02:45:55 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-t47zta-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
02:45:55 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))
02:45:55 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
02:45:55 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-8iavfz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
02:45:55 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))
02:45:55 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
02:45:55 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xbvlpc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
02:45:56 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))
02:45:56 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
02:45:56 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-slxu_s-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
02:45:56 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))
02:45:56 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
02:45:56 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-9t9xlb-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
02:45:56 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))
02:45:56 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
02:45:56 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pismci-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
02:45:56 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))
02:45:56 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))
02:45:56 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))
02:45:56 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))
02:45:56 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))
02:45:56 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))
02:45:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10))
02:45:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
02:45:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
02:45:57 INFO - Downloading blessings-1.6.tar.gz
02:45:57 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings
02:45:57 INFO - Installing collected packages: blessings
02:45:57 INFO - Running setup.py install for blessings
02:45:58 INFO - Successfully installed blessings
02:45:58 INFO - Cleaning up...
02:46:00 INFO - Return code: 0
02:46:00 INFO - Done creating virtualenv C:\slave\test\build/venv.
02:46:00 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
02:46:00 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
02:46:01 INFO - Reading from file tmpfile_stdout
02:46:01 INFO - Using _rmtree_windows ...
02:46:01 INFO - Using _rmtree_windows ...
02:46:01 INFO - Current package versions:
02:46:01 INFO - blessings == 1.6
02:46:01 INFO - blobuploader == 1.2.4
02:46:01 INFO - docopt == 0.6.1
02:46:01 INFO - functools32 == 3.2.3-2
02:46:01 INFO - jsonschema == 2.5.1
02:46:01 INFO - manifestparser == 1.1
02:46:01 INFO - mozInstall == 1.12
02:46:01 INFO - mozcrash == 0.17
02:46:01 INFO - mozdebug == 0.1
02:46:01 INFO - mozdevice == 0.48
02:46:01 INFO - mozfile == 1.2
02:46:01 INFO - mozhttpd == 0.7
02:46:01 INFO - mozinfo == 0.9
02:46:01 INFO - mozleak == 0.1
02:46:01 INFO - mozlog == 3.4
02:46:01 INFO - moznetwork == 0.27
02:46:01 INFO - mozprocess == 0.23
02:46:01 INFO - mozprofile == 0.28
02:46:01 INFO - mozrunner == 6.12
02:46:01 INFO - mozscreenshot == 0.1
02:46:01 INFO - mozsystemmonitor == 0.3
02:46:01 INFO - moztest == 0.7
02:46:01 INFO - mozversion == 1.4
02:46:01 INFO - psutil == 3.1.1
02:46:01 INFO - requests == 1.2.3
02:46:01 INFO - Installing None into virtualenv C:\slave\test\build/venv
02:46: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')]}
02:46:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:46: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')]}
02:46:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:46:01 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 0x01820640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018CBE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017CB228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018E8E90>, '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 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01E98520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-140', 'USERDOMAIN': 'T-W732-IX-140', '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-140', '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
02:46:01 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
02:46:01 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
02:46:01 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
02:46:01 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
02:46:01 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
02:46:01 INFO - 'COMPUTERNAME': 'T-W732-IX-140',
02:46:01 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
02:46:01 INFO - 'CYGWINBASE': 'C:\\cygwin',
02:46:01 INFO - 'DCLOCATION': 'SCL3',
02:46:01 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
02:46:01 INFO - 'FP_NO_HOST_CHECK': 'NO',
02:46:01 INFO - 'HOMEDRIVE': 'C:',
02:46:01 INFO - 'HOMEPATH': '\\Users\\cltbld',
02:46:01 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
02:46:01 INFO - 'KTS_VERSION': '1.19c',
02:46:01 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
02:46:01 INFO - 'LOGONSERVER': '\\\\T-W732-IX-140',
02:46:01 INFO - 'MONDIR': 'C:\\Monitor_config\\',
02:46:01 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
02:46:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:46:01 INFO - 'MOZ_NO_REMOTE': '1',
02:46:01 INFO - 'NO_EM_RESTART': '1',
02:46:01 INFO - 'NUMBER_OF_PROCESSORS': '8',
02:46:01 INFO - 'OS': 'Windows_NT',
02:46:01 INFO - 'OURDRIVE': 'C:',
02:46: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;',
02:46:01 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
02:46:01 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
02:46:01 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
02:46:01 INFO - 'PROCESSOR_LEVEL': '6',
02:46:01 INFO - 'PROCESSOR_REVISION': '1e05',
02:46:01 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
02:46:01 INFO - 'PROGRAMFILES': 'C:\\Program Files',
02:46:01 INFO - 'PROMPT': '$P$G',
02:46:01 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
02:46:01 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
02:46:01 INFO - 'PUBLIC': 'C:\\Users\\Public',
02:46:01 INFO - 'PWD': 'C:\\slave\\test',
02:46:01 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
02:46:01 INFO - 'SLAVEDIR': 'C:\\slave\\',
02:46:01 INFO - 'SYSTEMDRIVE': 'C:',
02:46:01 INFO - 'SYSTEMROOT': 'C:\\windows',
02:46:01 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
02:46:01 INFO - 'TEST1': 'testie',
02:46:01 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
02:46:01 INFO - 'USERDOMAIN': 'T-W732-IX-140',
02:46:01 INFO - 'USERNAME': 'cltbld',
02:46:01 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
02:46:01 INFO - 'WINDIR': 'C:\\windows',
02:46:01 INFO - 'WINDOWS_TRACING_FLAGS': '3',
02:46:01 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
02:46:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
02:46:01 INFO - Ignoring indexes: https://pypi.python.org/simple/
02:46:01 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))
02:46:01 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))
02:46:01 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))
02:46:01 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))
02:46:01 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))
02:46:01 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))
02:46:01 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))
02:46:01 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))
02:46:01 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))
02:46:01 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))
02:46:01 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))
02:46:01 INFO - Cleaning up...
02:46:01 INFO - Return code: 0
02:46:01 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv
02:46: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')]}
02:46:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:46: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')]}
02:46:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:46: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01820640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018CBE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017CB228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018E8E90>, '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 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01E98520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-140', 'USERDOMAIN': 'T-W732-IX-140', '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-140', '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
02:46: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', 'jsonschema'] in C:\slave\test\build
02:46: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 jsonschema
02:46:01 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
02:46:01 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
02:46:01 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
02:46:01 INFO - 'COMPUTERNAME': 'T-W732-IX-140',
02:46:01 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
02:46:01 INFO - 'CYGWINBASE': 'C:\\cygwin',
02:46:01 INFO - 'DCLOCATION': 'SCL3',
02:46:01 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
02:46:01 INFO - 'FP_NO_HOST_CHECK': 'NO',
02:46:01 INFO - 'HOMEDRIVE': 'C:',
02:46:01 INFO - 'HOMEPATH': '\\Users\\cltbld',
02:46:01 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
02:46:01 INFO - 'KTS_VERSION': '1.19c',
02:46:01 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
02:46:01 INFO - 'LOGONSERVER': '\\\\T-W732-IX-140',
02:46:01 INFO - 'MONDIR': 'C:\\Monitor_config\\',
02:46:01 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
02:46:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:46:01 INFO - 'MOZ_NO_REMOTE': '1',
02:46:01 INFO - 'NO_EM_RESTART': '1',
02:46:01 INFO - 'NUMBER_OF_PROCESSORS': '8',
02:46:01 INFO - 'OS': 'Windows_NT',
02:46:01 INFO - 'OURDRIVE': 'C:',
02:46: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;',
02:46:01 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
02:46:01 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
02:46:01 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
02:46:01 INFO - 'PROCESSOR_LEVEL': '6',
02:46:01 INFO - 'PROCESSOR_REVISION': '1e05',
02:46:01 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
02:46:01 INFO - 'PROGRAMFILES': 'C:\\Program Files',
02:46:01 INFO - 'PROMPT': '$P$G',
02:46:01 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
02:46:01 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
02:46:01 INFO - 'PUBLIC': 'C:\\Users\\Public',
02:46:01 INFO - 'PWD': 'C:\\slave\\test',
02:46:01 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
02:46:01 INFO - 'SLAVEDIR': 'C:\\slave\\',
02:46:01 INFO - 'SYSTEMDRIVE': 'C:',
02:46:01 INFO - 'SYSTEMROOT': 'C:\\windows',
02:46:01 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
02:46:01 INFO - 'TEST1': 'testie',
02:46:01 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
02:46:01 INFO - 'USERDOMAIN': 'T-W732-IX-140',
02:46:01 INFO - 'USERNAME': 'cltbld',
02:46:01 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
02:46:01 INFO - 'WINDIR': 'C:\\windows',
02:46:01 INFO - 'WINDOWS_TRACING_FLAGS': '3',
02:46:01 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
02:46:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
02:46:01 INFO - Ignoring indexes: https://pypi.python.org/simple/
02:46:01 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages
02:46:01 INFO - Cleaning up...
02:46:01 INFO - Return code: 0
02:46:01 INFO - Running post-action listener: _resource_record_post_action
02:46:01 INFO - Running post-action listener: _start_resource_monitoring
02:46:01 INFO - Starting resource monitoring.
02:46:02 INFO - [mozharness: 2016-09-28 09:46:02.054000Z] Finished create-virtualenv step (success)
02:46:02 INFO - [mozharness: 2016-09-28 09:46:02.054000Z] Running install step.
02:46:02 INFO - Running pre-action listener: _resource_record_pre_action
02:46:02 INFO - Running main action method: install
02:46:02 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
02:46:02 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
02:46:02 INFO - Reading from file tmpfile_stdout
02:46:02 INFO - Using _rmtree_windows ...
02:46:02 INFO - Using _rmtree_windows ...
02:46:02 INFO - Detecting whether we're running mozinstall >=1.0...
02:46:02 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h']
02:46:02 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h
02:46:02 INFO - Reading from file tmpfile_stdout
02:46:02 INFO - Output received:
02:46:02 INFO - Usage: mozinstall-script.py [options] installer
02:46:02 INFO - Options:
02:46:02 INFO - -h, --help show this help message and exit
02:46:02 INFO - -d DEST, --destination=DEST
02:46:02 INFO - Directory to install application into. [default:
02:46:02 INFO - "C:\slave\test"]
02:46:02 INFO - --app=APP Application being installed. [default: firefox]
02:46:02 INFO - Using _rmtree_windows ...
02:46:02 INFO - Using _rmtree_windows ...
02:46:02 INFO - mkdir: C:\slave\test\build\application
02:46:02 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']
02:46:02 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
02:46:05 INFO - Reading from file tmpfile_stdout
02:46:05 INFO - Output received:
02:46:05 INFO - C:\slave\test\build\application\firefox\firefox.exe
02:46:05 INFO - Using _rmtree_windows ...
02:46:05 INFO - Using _rmtree_windows ...
02:46:05 INFO - Running post-action listener: _resource_record_post_action
02:46:05 INFO - [mozharness: 2016-09-28 09:46:05.152000Z] Finished install step (success)
02:46:05 INFO - [mozharness: 2016-09-28 09:46:05.152000Z] Running run-tests step.
02:46:05 INFO - Running pre-action listener: _resource_record_pre_action
02:46:05 INFO - Running main action method: run_tests
02:46:05 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version']
02:46:05 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version
02:46:05 INFO - Python 2.7.3
02:46:05 INFO - Return code: 0
02:46:05 INFO - grabbing minidump binary from tooltool
02:46:05 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')]}
02:46:05 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 0x018E8E90>, '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 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01E98520>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1
02:46:05 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
02:46:05 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
02:46:05 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
02:46:05 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'...
02:46:05 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmpcjy1bn
02:46:05 INFO - INFO - File integrity verified, renaming tmpcjy1bn to win32-minidump_stackwalk.exe
02:46:05 INFO - Return code: 0
02:46:05 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755
02:46:05 INFO - mkdir: C:\slave\test\build\blobber_upload_dir
02:46:05 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir
02:46:05 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe
02:46:05 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir
02:46:05 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dFMBNftxRKKCcOFSw43h-g/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-140', '--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
02:46:05 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/dFMBNftxRKKCcOFSw43h-g/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-140 --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
02:46:05 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
02:46:05 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
02:46:05 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
02:46:05 INFO - 'COMPUTERNAME': 'T-W732-IX-140',
02:46:05 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
02:46:05 INFO - 'CYGWINBASE': 'C:\\cygwin',
02:46:05 INFO - 'DCLOCATION': 'SCL3',
02:46:05 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
02:46:05 INFO - 'FP_NO_HOST_CHECK': 'NO',
02:46:05 INFO - 'HOMEDRIVE': 'C:',
02:46:05 INFO - 'HOMEPATH': '\\Users\\cltbld',
02:46:05 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
02:46:05 INFO - 'KTS_VERSION': '1.19c',
02:46:05 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
02:46:05 INFO - 'LOGONSERVER': '\\\\T-W732-IX-140',
02:46:05 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir',
02:46:05 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe',
02:46:05 INFO - 'MONDIR': 'C:\\Monitor_config\\',
02:46:05 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
02:46:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:46:05 INFO - 'MOZ_NO_REMOTE': '1',
02:46:05 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir',
02:46:05 INFO - 'NO_EM_RESTART': '1',
02:46:05 INFO - 'NUMBER_OF_PROCESSORS': '8',
02:46:05 INFO - 'OS': 'Windows_NT',
02:46:05 INFO - 'OURDRIVE': 'C:',
02:46:05 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;',
02:46:05 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
02:46:05 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
02:46:05 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
02:46:05 INFO - 'PROCESSOR_LEVEL': '6',
02:46:05 INFO - 'PROCESSOR_REVISION': '1e05',
02:46:05 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
02:46:05 INFO - 'PROGRAMFILES': 'C:\\Program Files',
02:46:05 INFO - 'PROMPT': '$P$G',
02:46:05 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
02:46:05 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
02:46:05 INFO - 'PUBLIC': 'C:\\Users\\Public',
02:46:05 INFO - 'PWD': 'C:\\slave\\test',
02:46:05 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos',
02:46:05 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
02:46:05 INFO - 'SLAVEDIR': 'C:\\slave\\',
02:46:05 INFO - 'SYSTEMDRIVE': 'C:',
02:46:05 INFO - 'SYSTEMROOT': 'C:\\windows',
02:46:05 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
02:46:05 INFO - 'TEST1': 'testie',
02:46:05 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
02:46:05 INFO - 'USERDOMAIN': 'T-W732-IX-140',
02:46:05 INFO - 'USERNAME': 'cltbld',
02:46:05 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
02:46:05 INFO - 'WINDIR': 'C:\\windows',
02:46:05 INFO - 'WINDOWS_TRACING_FLAGS': '3',
02:46:05 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
02:46:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
02:46:05 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dFMBNftxRKKCcOFSw43h-g/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-140', '--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
02:46:06 INFO - mozversion application_buildid: 20160928010920
02:46:06 INFO - mozversion application_changeset: 52cfd2babd94fa8452128940b50614cc12660785
02:46:06 INFO - mozversion application_display_name: Nightly
02:46:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
02:46:06 INFO - mozversion application_name: Firefox
02:46:06 INFO - mozversion application_remotingname: firefox
02:46:06 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team
02:46:06 INFO - mozversion application_vendor: Mozilla
02:46:06 INFO - mozversion application_version: 52.0a1
02:46:06 INFO - mozversion platform_buildid: 20160928010920
02:46:06 INFO - mozversion platform_changeset: 52cfd2babd94fa8452128940b50614cc12660785
02:46:06 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team
02:46:06 INFO - mozversion platform_version: 52.0a1
02:46:06 INFO - using testdate: 1475055966
02:46:06 INFO - actual date: 1475055966
02:46:06 INFO - starting webserver on 'localhost:49893'
02:46:06 INFO - SUITE-START | Running 2 tests
02:46:06 INFO - TEST-START | dromaeo_css
02:46:06 INFO - operating with platform_type : w7_
02:46:06 INFO - Initialising browser for dromaeo_css test...
02:46:06 INFO - TEST-INFO | started process 2924 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpxhdmdf\profile http://localhost:49893/getInfo.html)
02:46:14 INFO - PROCESS | 2924 | __metrics Screen width/height:1600/1200
02:46:14 INFO - PROCESS | 2924 | colorDepth:24
02:46:14 INFO - PROCESS | 2924 | Browser inner width/height: 1010/674
02:46:14 INFO - PROCESS | 2924 | __metrics
02:46:14 INFO - PROCESS | 2924 | 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"
02:46:14 INFO - PROCESS | 2924 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
02:46:15 INFO - TEST-INFO | 2924: exit 0
02:46:15 INFO - Browser initialized.
02:46:15 INFO - Running cycle 1/1 for dromaeo_css test...
02:46:15 INFO - TEST-INFO | started process 1408 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpxhdmdf\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1)
02:46:16 INFO - PROCESS | 1408 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0
02:46:27 INFO - PROCESS | 1408 | RSS: Main: 139452416
02:46:27 INFO - PROCESS | 1408 |
02:47:18 INFO - PROCESS | 1408 | 1475056038938 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]
02:49:32 INFO - PROCESS | 1408 | Cycle 1(1): loaded http://localhost:49893/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49893/tests/dromaeo/cssquery-ext.html)
02:49:33 INFO - PROCESS | 1408 | RSS: Main: 188694528
02:49:33 INFO - PROCESS | 1408 |
02:52:36 INFO - PROCESS | 1408 | Cycle 1(1): loaded http://localhost:49893/tests/dromaeo/cssquery-ext.html (next: http://localhost:49893/tests/dromaeo/cssquery-jquery.html)
02:52:37 INFO - PROCESS | 1408 | RSS: Main: 202756096
02:52:37 INFO - PROCESS | 1408 |
02:55:39 INFO - PROCESS | 1408 | Cycle 1(1): loaded http://localhost:49893/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49893/tests/dromaeo/cssquery-mootools.html)
02:55:40 INFO - PROCESS | 1408 | RSS: Main: 144244736
02:55:40 INFO - PROCESS | 1408 |
02:58:42 INFO - PROCESS | 1408 | Cycle 1(1): loaded http://localhost:49893/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49893/tests/dromaeo/cssquery-prototype.html)
02:58:43 INFO - PROCESS | 1408 | RSS: Main: 141496320
02:58:43 INFO - PROCESS | 1408 |
03:01:45 INFO - PROCESS | 1408 | Cycle 1(1): loaded http://localhost:49893/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49893/tests/dromaeo/cssquery-yui.html)
03:01:46 INFO - PROCESS | 1408 | RSS: Main: 140840960
03:01:46 INFO - PROCESS | 1408 |
03:04:49 INFO - PROCESS | 1408 | Cycle 1(1): loaded http://localhost:49893/tests/dromaeo/cssquery-yui.html (next: http://localhost:49893/tests/dromaeo/cssquery-dojo.html)
03:04:49 INFO - PROCESS | 1408 | RSS: Main: 190869504
03:04:49 INFO - PROCESS | 1408 |
03:04:49 INFO - PROCESS | 1408 | __start_tp_report
03:04:49 INFO - PROCESS | 1408 | _x_x_mozilla_page_load
03:04:49 INFO - PROCESS | 1408 | _x_x_mozilla_page_load_details
03:04:49 INFO - PROCESS | 1408 | |i|pagename|runs|
03:04:49 INFO - PROCESS | 1408 | |0;dojo.html;2311.9884400577994;2322.187234467936;2357.976420235797;2362.905483780649;2366.5030343627836;3931.8427262909486;3947.368421052625;4024.215278020787;4035.2534781065488;4038.131801662642;3780.2817464681693;3874.961250387495;3899.961000389995;4011.3782363733594;4099.610536998988;3467.826608669569;3498.6501349865002;3535.3282876253556;3541.291741651667;3553.5913369962395;3472.8263586820685;3489.092835862681;3502.9299414011703;3508.6842184203415;3551.698105661022;3443.173638326796;3486.0936156599214;3549.432090865462;3555.608883022878;3558.270554536327;3375;3480.930381392358;3545.2909418116224;3551.378508760982;3555.3955827509258;3003.669596344422;3393.8133402662843;3415.1803567143957;3474.617792042873;3489.0753950203216;3829.5787463379006;3897.707671924617;3903.180332130256;3979.8806035818684;3984;4106.7125301228925;4184.4351012613515;4266.1254442839145;4267.573242675739;4267.573242675739;4156;4223.324268117087;4262.1049579588325;4350.0647360817575;4353.782310884475;3950.170464202492;3977.065389633434;4037.7577345359373;4041.5958404159937;4050.8379664813665;4096.078382363944;4296.140771845643;4320.114376552862;4407.647388208936;4409.801558929856;4092.9181416372103;4119.670426365884;4184.184084103571;4191.497020357516;4221.113566151082;4194.664426845845;4218.7679677618025;4257.787110644455;4260.978695106566;4277.443932288845;4161.8959526012095;4192.664586833046;4251.829926802893;4260.25445547028;4275.3800698898485;4109.3425051991535;4131;4153.397757325171;4157.542670306264;4193.643540299109;2905;2908.0258113532204;2951.8228906265695;2954.069468117536;2957.6746557878614;2645.52380571499;2694.7170547092273;2739;2745.1901689001793;2754.228815931542;3830.6743926766535;3907.8046097695;3914.3149948758855;3948.881533553998;3974.6621537169085;4230.450041494586;4305.9569404306185;4309.784510774449;4313.460817397825;4317.330813723878;3950.3876899080688;3953.1303113315025;3989.4813674222164;4031.6169963853386;4040.333344998101;3555.1645363339594;3567.8216089195435;3581.086822860154;3584.336897673939;3620.402633565485;4358.389825424507;4469.039156581351;4478.865634030984;4480.865574032784;4490.955090449053;4266.957330426656;4290.334998075304;4342.652587793033;4357.651387889025;4361.978190109029;4252.4897012358715;4291.485021797404;4340.348947657877;4458.06380660065;4479.888002799956;3994.1013272014725;4102.876913692594;4106.2198182345255;4146.087860670647;4194.853180138681;4152.356384760367;4245.384419259251;4263.2752432086;4277.101808620225;4286.399904013503;3932.174243408916;4053.6959728021106;4057.4319595256097;4133.648639865645;4140.192662430917;4034.11249525104;4109.547949725587;4114.629683328515;4154.065335299533;4163.437935878617;3969.293203922341;3977.423273625366;3991.840326386912;4018.216447792652;4035.41486484464;4061.390791381198;4095.365218391154;4120.423140760236;4183.39340795589;4210.431591735077;4073.144639625712;4118.320477121241;4129.091599848029;4166.68749843769;4180.665546756191
03:04:49 INFO - PROCESS | 1408 | |1;ext.html;540.1816248383698;541.4937033873329;556.0075265650814;559.5439716630946;589.3399392680193;26295.211143665674;34646.32274642922;34899.47650785237;35642.64357356426;39165;36810.8159459203;36917.26165476689;37259.4411083834;37308;37311.25377492449;6456.289808121103;7494.700211991528;7665.156832748393;7742.729004484844;7772.494787838772;6376.681165941707;6628.900566491507;6644.069830223772;6651.368120028589;9547.80904381912;10107.747306317327;10173.898261017406;10174.236932230075;10215;10215.336003159808;10040;10122.443265620388;10135.037171468699;10135.44255065971;10140.594376224943;6299.433051025429;6493.22081350241;9136.406133601362;9894.30739848211;9974.102330790263;31674;33287.6671233287;34163;34648.82675586606;34826.12934676628;2584.431425086497;2586.3792689754323;2610.0212420342373;2632.181505042346;2667.66654168229;25109.232168687406;25667;26559.73440265592;27315.590266146024;27428.862855685795;3094.6131733533307;3138.2625083105454;3178.4596618574665;3283.770136090475;3291.9880654206627;205312;211875;212049;212220;212448;46612;48852.267215991786;48992.26511602257;49013.50986490161;49022.50977490251;51350;51411;51575;51608.483915161116;51688.48311516912;11105.888941110647;11449;11758.647240582624;11860.584879529175;11861.466234019317;8736.956315218384;8768.342374321952;8809.33929955256;8811.251043661361;8853.557322133867;13533.796993044914;14050.508232211823;15427.382904683911;16434;16977.641788656878;6657.267700552935;6770.804877443048;6831.965840170867;6832.0776695146515;6844.8973265401055;724.1201939643358;767.5548182054395;803.9839203215903;859.656137544987;924.2929159193222;23874.283771486535;25449.363765906;25797.226083217534;25823.483530329664;25924.61113083305;871.3116637856023;885.4023534114447;910.2308549275851;922.7646950027835;970.3401686852867;13607.863921360859;13613.319334033258;13736.381862816197;13830;13861.584152475441;287846;291082;292167;293271;295067;41744.79127604343;43949;44631;44773.776131119135;44880.77559612181;958.5830163878735;989.9653512127041;1058.7776566921034;1113.2151832957907;1114.3648120571359;25119.748802512473;26107.347316317246;26485.735142649097;26494.205173844817;26606;36982.26035479221;37168.6283137176;37241;37408.81295593505;37788.24423511569;221901;223870;225530;343109;392401;33270.334593308486;33502.497462538566;34426.48360274648;34502.30995380128;34621.65378346285;37369.69206077774;37933.62066379301;38280.234395312495;38570.228595428496;38742.6125738739;31329.530057048705;31407.685923140478;32080.83959580187;32578.83710581432;33144.83427582846;32689;32954;33423.9836829373;33524.49713254352;33744.938259261304;31503.527447087854;31557.211069723442;31901.840490797396;32022.519662205552;32052.35895282128;28596.14211573573;29053.7094629051;29780.70219297866;30247.69752302449;30585.54121688221;21821.781782181974;23158.42103947415;23168;23355;23625.64561531544
03:04:49 INFO - PROCESS | 1408 | |2;jquery.html;284.87465515173307;313.1404295209649;318.9409959157556;320.16756433273486;321.2883463129168;10916.181286403522;15499.38002479901;15929.725621950245;16080.437184698538;17220.39728609499;13398.263095529766;15320.387184512634;15579.766303505428;15719.13544755038;15737;4870.147724148278;5137.7944882204665;5146.536811686948;5150.793968241266;5161.9741901290445;3536.222031153155;3570.9822700530353;3716.4796928429905;4999.200127979524;5069.0115427491655;3727.9813600932093;5832.533397328203;6005.2193214882;6007.519398448136;6014.578979471439;5650.51970582501;5899.823005309848;5947.940520594782;5977.880442391128;5980.43185897339;5087.160618497944;5111;5114.872128196787;5121.487851214849;5123.026624941286;19617.509562261057;20232.79767202324;20978.685319720218;21282.14871405142;21302.78697213039;1822.3166312632763;1839.4573600787667;1855.9350422735274;2186.5954798362195;2567.82025258232;14127.929360353235;14141.646458838612;14233.146011239358;14257.287135643177;14524.419023239057;1735.687576236277;1882.1624377152173;1919.424172748184;2070.171931227506;2103.4951611613255;72215.63892180558;75457;76151.23848761497;76237;77347.61326193389;14812.555623331102;14965.40138394452;15035.924820375829;15330;15410.460633877985;21662.56674866494;22835.429114272276;23274.65088023681;24696.753032469805;26409.73590264073;7371.262873712693;7476.476646634792;7655.043119610049;7696.191899850547;7760.185180556074;6188.81433556985;6226.844328891814;6296.488842677816;6315.494760419156;6318.399752023551;7820.139784623687;7856.725014624575;7927.048754149539;7945;8004.759857204293;3242.935141297161;3253.8535765890592;3256.1859535116223;3333.9833300833825;3335.332933413327;296.26082923106986;767.9539227646359;768.7078910014159;788.0661416221681;814.4746638418187;8900.376973611785;9408.153266206074;9426.340156189139;9540.809183816285;9617.663381781604;321.8004837001074;323.40977715669;325.3006037020378;330.6478600290683;332.8202770503949;4954.3311652926395;5031.597472202215;5078.796848126108;5176.327077479904;5189.636725429184;293391;295275;296073;297726;299181;75455;75986.62006690042;76255;76840.61579692065;79725.60137199392;753.2279413601103;794.6860989909069;796.3947399976059;798.3174385900088;800.6837299266873;14780.260986950394;15042.172680502677;15130.697386052438;15244.542663720105;15447.768283475982;26975.595366069134;32742.50886236756;37020.44469333016;37588.436173457965;39783.80108099441;45017;46792.298115527614;48381.27428088511;48632;49296.75351623219;6383.585066970632;6629.138212032405;6713.362230588086;6749.358810912955;6780.491463140185;10039.24705647094;10832.620858270238;11036.392998385167;11232.606858759904;11392.601258956229;8358.205970432798;8773.824523509622;8846.513441760508;8914.064023277462;9082.000979892338;8537;8748.956255218684;8790.296776258012;8866.64533418656;9000.099990000948;9130.223930965942;9221.216196623362;9320.347575669639;9389.765255868659;9478.336516444058;6797.9320206799275;6854.897176542256;6975.511714180162;7082.787516374517;7211.242819504087;7761.9611901940125;8564.52895090751;8906.73279801607;8946.37375383743;9040.864387034067
03:04:49 INFO - PROCESS | 1408 | |3;mootools.html;1165.8018136916724;1178.7524619829835;1184.6150001249594;1408.478862820756;1461.9195944223063;5844.912326315103;6189.412005859447;6203.2245969253845;6207.006878899366;6224.128621992919;5261.368635763713;5356.651817631851;5733.48398644122;5911.9113213301835;6116.0214365701495;1748.501677022049;1751.1506919144238;1764.4971183212788;1766.4435702753656;1770.876038677293;1734.4883259438434;1756.4379398592455;1760.0847559269173;1763.9382621608247;1768.3191971091157;1527.8472152784743;1579.778830963666;1732.9133543322846;1760.7270873014638;1773.3970450046982;1788.2131861980752;1793.6143729098308;1805.1515787579817;1805.702059160237;1810.4568629411124;1656.5941344370706;1695.610009697764;1696.7285234362566;1700.7108791505473;1703.8466538011514;5894.351621321651;5931.970340148315;5957;5993.0710739835395;5993.730282137314;3377.5946886373545;3555.5022296878456;3562.6437356264423;3565.536480257576;3566.625504322061;2185.8032777049984;2196.890155492235;2244;2276.3057267533395;2283.7259528856644;725.8294300839351;754.4718696912183;755.9848803023908;757.3562471898897;759.7910574591976;7655.425843061848;7679.078510578766;7686.039245094364;7688.423368247348;7691.846163076707;3091.5980922479935;3107.207662046164;3164.0824160993516;3165.7467402607735;3184.8726050958167;6562.770303039466;6660.167479065117;6703;6704.3295670433545;6740.258571557125;3435.1412146963257;3457.75795694299;3464.913377165591;3498.1429549760473;3540.274243780019;3140.5132204508336;3197;3199.4560924642865;3201.807891526516;3207.0378886334474;2784.7911406644585;2822.8729707163225;2849.7577705895233;2893.8263704177816;2899.7680185585523;908.4685459006555;915.3226612306845;920.7191806498948;921.8202450522218;924.0528458330265;579.4813641790574;719.9928000720031;736.8121129112042;756.7767508584981;784.9882251766228;3886.3781794913243;3923.195744872294;3928.9784655989647;4019.718619696593;4053.1488387438967;788.5544667263093;792.3186059988406;796.3788245168662;798.7124635131348;799.2846402469758;2841.318083659948;2879.4960881845595;2970.435617232762;2997.2057404787747;3001.789874708793;6489.967550162219;6946.930530694628;6981.301869812978;7051.294870512907;7073.328033836778;7001.089858318488;7121.89317160243;7133.928660713326;7182.784516464514;7201.4238860891;5612.410696876769;5753.741081651336;5763.9135412968835;5774.105013722879;5774.62464939786;4119.97940010298;4164.812583433692;4252.383404406406;4264.744115353089;4265.933516620845;6435.2599451063725;6623.867522649616;6645.568038077509;6645.800625981228;6756.121704178426;6650.90023649636;6684.799456016327;6826.883666063536;6837.453003759588;6850.725970961305;5518.972405137949;5550.8057217997175;5666.376698563073;5671.773129074791;5690.317161940594;5608.719564021864;5662;5692.31692196939;5695.658260504383;5718.0565206742085;5018.447970723145;5023.715943954034;5035;5038.420581633159;5077.619178561548;4718.504557021601;4941.184704523714;4941.77762000718;5126.4360920297995;5131.179011358165;4697.8355757548325;4741.976290118665;4747.311639812277;4756.191447453872;4780.211265141213;3392.8473218705712;3409.687270400864;3435.8282085896103;3447.5862896452586;3451.6375780543685;3451.5167876496807;3513.069036705224;3560.0565850050275;3591.6587924147166;3616.8914932552066
03:04:49 INFO - PROCESS | 1408 | |4;prototype.html;222.0208878844295;250.41902785537548;250.68789357250222;252.39677171559964;258.50754313033667;4119;4217.240896638604;4335.458067741532;4383.956160438394;4392.12157568486;4257.382679511469;4294.484661840584;4300.806463709133;4326.675499337554;4326.762028088454;3177.920551986207;3234.8867789627366;3236.4659831127838;3240.7731458797775;3248.090534650302;3181.22060095277;3237.7086062254393;3238.5142228665773;3246.7077962983326;3253.7722359434847;3148;3182.5226216067545;3201.887933922313;3224.9677503224902;3231.482962725964;3200.927689224101;3211.74306055515;3218.3080637663015;3228.0315905228454;3231.676832316773;2782.8154898404655;2840.105366809469;2845.601615773793;2852.9001484947926;2859.985700071507;4175.185838761443;4195.832166713328;4214.894627634303;4222.514410842762;4223.619874211305;3835.4438606401914;3838.7312888097845;3840.635139661728;3841.2893614681375;3844.8462061517785;4266.424032755569;4447.043885564619;4460.576245256695;4486.955130448687;4507.143642707908;3130.6712795156636;3134.451470992567;3141.8271995040263;3198.040587823644;3200.3759266942957;4620.3993480847275;4640.651951103647;4652.348671186037;4680.274557443568;4686.390769199982;3056.9453538529174;3176.142441540771;3179.8728050877708;3191.1543440988157;3194.520821876737;3185.904422867318;3210.197450637341;3212.213007813054;3217.260030193069;3225.3871764364694;3646.9452958205648;3722.274156539449;3728.1611637381916;3735.3836616957897;3748.194138260287;4532.27483602622;4535.457944298954;4535.63714902807;4537.342085397665;4543.159515489579;1085.2891356161726;1112.7481583218878;1116.6873275482878;1122.3209957975307;1123.4607388453587;699.9335063168991;702.4450683959717;717.5873872523216;720.6216736213426;726.3584299483206;3883.728139030241;3890.3580909149673;3894.7078969077434;3929.135590170158;3957.6240257175523;4167.3952031493145;4170.332746760504;4216.64158546527;4229.746215227096;4232.407462955189;3876.7867767272505;3881.514810648669;3925.8811238796807;3951.7233793634746;3956.1296514766705;2225.910963561472;2238.429200553848;2244.9897546104316;2260.1298500077587;2371.952560948806;4400.383946247528;4548.333465283844;4742.715437073718;4786.042791441726;4799.808007679723;4771.900363839899;4773.188557945157;4777.211760059551;4780.450248221429;4788.138135135709;4716.3632909557455;4732.219183834629;4807.6394270429855;4810.975945120252;4820.517948205222;4524.457065152071;4546.840250880805;4682.555157260055;4691.554302341272;4703.412073490814;4457.977710111429;4532.713782507655;4534.659900507541;4583.266677331744;4590.862274131781;4722.969123277024;4781.497942716104;4784.354112194809;4793.760311984456;4794.904101917872;3172.809631422122;3178.25311051898;3185.976904665508;3198.584184056151;3200.655724595722;4539.909201815879;4605.83879564227;4631.8378856739855;4633.536646335339;4636.744979026186;4230.44058344697;4372.693911426297;4384.780760962003;4483.932741008823;4490.034642551867;4450.354698568624;4453.933191002073;4479.260921947842;4490.405905903357;4494.865154045384;4300.182965236595;4319.488179137277;4406.691531592886;4408.713433626804;4411.51473337939;4469.307257375112;4522.977385113185;4531.365608814835;4545.136424079415;4553.2259515881715;4151.152737031992;4261.659067274672;4327.372530982927;4380.583844534892;4423.579759922803
03:04:49 INFO - PROCESS | 1408 | |5;yui.html;603.827909045922;611.9724612392442;615.7413886167809;629.7040391016224;651.589498615872;12368.381580920952;12890.226586404822;12993.220406775588;13066.93466532666;13507.594772156852;11320.490577923993;11426.7143321417;11448.942755286213;11469.139814513903;11490.253133546314;636.6084857812458;641.2337256977902;654.5745265577365;657.5495785387026;660.3528542028814;506.87581542522133;519.1434133679421;632.3960617610171;646.9579477333982;658.5390226841206;696.7909627111846;707.7628994286895;708.2740191303903;708.6988030087218;709.595530547588;700.9369156775864;701.8701540215077;703.0649236515422;703.6938931564786;704.9048378468892;389.02355088727495;394.18207220018576;395.2490268489892;497.39814824062927;514.4546780412775;10006.9499652502;10023.799524009552;11328.716782080432;11811.46848391816;11838.112141589416;12561.685957851036;12659.746805063847;12732.427040783186;12794.808077878839;13145.60563183106;10147.391156530632;10221.437820919846;10366.481675916173;10514;10700.090492308085;3172.413103575823;3208.1338038729646;3258.902232933016;3330.6169790473923;3345.5985281766343;63312;64860.02709959354;65378.67310663416;66674;66812;9580.808383832284;9634.181094607036;9674.322797404255;9823.115919567274;9986.500674966223;18426.49896519657;18472.168752406174;18534.81465185358;18548;18563.72154417685;6570.868582628416;6587.703553340016;6617.272100068989;6726.1256036715895;6774.288699686561;3294.4893541501106;3429.725621950238;3453.9654603453646;3471.0628130405153;3516.771409858402;2394.772496612819;2484.5403600334;2498.7626175513296;2503.5493611149805;2508.397984483711;399.02239513192796;400.96391324780325;402.13741524429935;409.83401722302517;410.2574340443806;561.6949739177697;580.5471732049009;599.3287517979891;615.53527087049;620.0265583034592;7883.2116788321855;7884.763457096296;7928.920710792819;8159.26566609008;8281.37889658278;467.86431934739227;517.9715115668674;592.3040427497673;592.8725324055349;631.9936800632026;3814.3896976484184;4049.67844299762;4155.293600087932;4165.791710414528;4282.700210985264;65239;94227;94446.52776736072;95305.52347238219;96114;81788.59105704434;82676;83677;83838;85568;39727;62831;73325.63337183387;73388.26611733921;74676.25323746802;7872.645730942007;7970.721024764105;8041.356691464552;8104.392170587112;8307.335413166811;41426.792866035474;42276;43257.78371108124;43542.34686479642;44799.328010079225;58439.415605843395;60392.39607604044;60412.395876040675;61141.38858611535;61716;10633.361998280128;10837.512311945824;10847.837282440927;10956.34261944286;10999.725006874893;11857.881421186023;11976.161668683457;12211.26732396059;12270.75458490843;12290.20113692607;11423.428828558704;11473.483693233658;11488.425578720864;11597.33280254757;11851.348175850411;11660.825087623523;11852.492163087423;12014.579489717818;12148.817767733315;12506.249625022163;10839.837402439127;11347.54609815598;11478.483468243781;11506.424678766196;11986.820197696867;9854.852177217204;10041.548130334006;10060.49697515136;10602;10675.626353077605;9858.753531161778;9864.358816676893;9899.802003959736;9984.500774961369;10025.699229023141
03:04:49 INFO - PROCESS | 1408 | __end_tp_report
03:04:49 INFO - PROCESS | 1408 | __start_cc_report
03:04:49 INFO - PROCESS | 1408 | _x_x_mozilla_cycle_collect,908
03:04:49 INFO - PROCESS | 1408 | __end_cc_report
03:04:49 INFO - PROCESS | 1408 | __startTimestamp1475057089734__endTimestamp
03:04:49 INFO - PROCESS | 1408 |
03:04:49 INFO - PROCESS | 1408 | ------- Summary: start -------
03:04:49 INFO - PROCESS | 1408 | Number of tests: 6
03:04:49 INFO - PROCESS | 1408 |
03:04:49 INFO - PROCESS | 1408 | [#0] dojo.html Cycles:165 Average:3895.66 Median:4053.70 stddev:490.43 (12.1%) stddev-sans-first:475.94
03:04:49 INFO - PROCESS | 1408 | Values: 2312.0 2322.2 2358.0 2362.9 2366.5 3931.8 3947.4 4024.2 4035.3 4038.1 3780.3 3875.0 3900.0 4011.4 4099.6 3467.8 3498.7 3535.3 3541.3 3553.6 3472.8 3489.1 3502.9 3508.7 3551.7 3443.2 3486.1 3549.4 3555.6 3558.3 3375.0 3480.9 3545.3 3551.4 3555.4 3003.7 3393.8 3415.2 3474.6 3489.1 3829.6 3897.7 3903.2 3979.9 3984.0 4106.7 4184.4 4266.1 4267.6 4267.6 4156.0 4223.3 4262.1 4350.1 4353.8 3950.2 3977.1 4037.8 4041.6 4050.8 4096.1 4296.1 4320.1 4407.6 4409.8 4092.9 4119.7 4184.2 4191.5 4221.1 4194.7 4218.8 4257.8 4261.0 4277.4 4161.9 4192.7 4251.8 4260.3 4275.4 4109.3 4131.0 4153.4 4157.5 4193.6 2905.0 2908.0 2951.8 2954.1 2957.7 2645.5 2694.7 2739.0 2745.2 2754.2 3830.7 3907.8 3914.3 3948.9 3974.7 4230.5 4306.0 4309.8 4313.5 4317.3 3950.4 3953.1 3989.5 4031.6 4040.3 3555.2 3567.8 3581.1 3584.3 3620.4 4358.4 4469.0 4478.9 4480.9 4491.0 4267.0 4290.3 4342.7 4357.7 4362.0 4252.5 4291.5 4340.3 4458.1 4479.9 3994.1 4102.9 4106.2 4146.1 4194.9 4152.4 4245.4 4263.3 4277.1 4286.4 3932.2 4053.7 4057.4 4133.6 4140.2 4034.1 4109.5 4114.6 4154.1 4163.4 3969.3 3977.4 3991.8 4018.2 4035.4 4061.4 4095.4 4120.4 4183.4 4210.4 4073.1 4118.3 4129.1 4166.7 4180.7
03:04:49 INFO - PROCESS | 1408 |
03:04:49 INFO - PROCESS | 1408 | [#1] ext.html Cycles:180 Average:41083.21 Median:33279.00 stddev:70034.95 (210.4%) stddev-sans-first:70165.26
03:04:49 INFO - PROCESS | 1408 | Values: 540.2 541.5 556.0 559.5 589.3 26295.2 34646.3 34899.5 35642.6 39165.0 36810.8 36917.3 37259.4 37308.0 37311.3 6456.3 7494.7 7665.2 7742.7 7772.5 6376.7 6628.9 6644.1 6651.4 9547.8 10107.7 10173.9 10174.2 10215.0 10215.3 10040.0 10122.4 10135.0 10135.4 10140.6 6299.4 6493.2 9136.4 9894.3 9974.1 31674.0 33287.7 34163.0 34648.8 34826.1 2584.4 2586.4 2610.0 2632.2 2667.7 25109.2 25667.0 26559.7 27315.6 27428.9 3094.6 3138.3 3178.5 3283.8 3292.0 205312.0 211875.0 212049.0 212220.0 212448.0 46612.0 48852.3 48992.3 49013.5 49022.5 51350.0 51411.0 51575.0 51608.5 51688.5 11105.9 11449.0 11758.6 11860.6 11861.5 8737.0 8768.3 8809.3 8811.3 8853.6 13533.8 14050.5 15427.4 16434.0 16977.6 6657.3 6770.8 6832.0 6832.1 6844.9 724.1 767.6 804.0 859.7 924.3 23874.3 25449.4 25797.2 25823.5 25924.6 871.3 885.4 910.2 922.8 970.3 13607.9 13613.3 13736.4 13830.0 13861.6 287846.0 291082.0 292167.0 293271.0 295067.0 41744.8 43949.0 44631.0 44773.8 44880.8 958.6 990.0 1058.8 1113.2 1114.4 25119.7 26107.3 26485.7 26494.2 26606.0 36982.3 37168.6 37241.0 37408.8 37788.2 221901.0 223870.0 225530.0 343109.0 392401.0 33270.3 33502.5 34426.5 34502.3 34621.7 37369.7 37933.6 38280.2 38570.2 38742.6 31329.5 31407.7 32080.8 32578.8 33144.8 32689.0 32954.0 33424.0 33524.5 33744.9 31503.5 31557.2 31901.8 32022.5 32052.4 28596.1 29053.7 29780.7 30247.7 30585.5 21821.8 23158.4 23168.0 23355.0 23625.6
03:04:49 INFO - PROCESS | 1408 |
03:04:49 INFO - PROCESS | 1408 | [#2] jquery.html Cycles:180 Average:21717.98 Median:5082.98 stddev:49867.56 (981.1%) stddev-sans-first:49981.49
03:04:49 INFO - PROCESS | 1408 | Values: 284.9 313.1 318.9 320.2 321.3 10916.2 15499.4 15929.7 16080.4 17220.4 13398.3 15320.4 15579.8 15719.1 15737.0 4870.1 5137.8 5146.5 5150.8 5162.0 3536.2 3571.0 3716.5 4999.2 5069.0 3728.0 5832.5 6005.2 6007.5 6014.6 5650.5 5899.8 5947.9 5977.9 5980.4 5087.2 5111.0 5114.9 5121.5 5123.0 19617.5 20232.8 20978.7 21282.1 21302.8 1822.3 1839.5 1855.9 2186.6 2567.8 14127.9 14141.6 14233.1 14257.3 14524.4 1735.7 1882.2 1919.4 2070.2 2103.5 72215.6 75457.0 76151.2 76237.0 77347.6 14812.6 14965.4 15035.9 15330.0 15410.5 21662.6 22835.4 23274.7 24696.8 26409.7 7371.3 7476.5 7655.0 7696.2 7760.2 6188.8 6226.8 6296.5 6315.5 6318.4 7820.1 7856.7 7927.0 7945.0 8004.8 3242.9 3253.9 3256.2 3334.0 3335.3 296.3 768.0 768.7 788.1 814.5 8900.4 9408.2 9426.3 9540.8 9617.7 321.8 323.4 325.3 330.6 332.8 4954.3 5031.6 5078.8 5176.3 5189.6 293391.0 295275.0 296073.0 297726.0 299181.0 75455.0 75986.6 76255.0 76840.6 79725.6 753.2 794.7 796.4 798.3 800.7 14780.3 15042.2 15130.7 15244.5 15447.8 26975.6 32742.5 37020.4 37588.4 39783.8 45017.0 46792.3 48381.3 48632.0 49296.8 6383.6 6629.1 6713.4 6749.4 6780.5 10039.2 10832.6 11036.4 11232.6 11392.6 8358.2 8773.8 8846.5 8914.1 9082.0 8537.0 8749.0 8790.3 8866.6 9000.1 9130.2 9221.2 9320.3 9389.8 9478.3 6797.9 6854.9 6975.5 7082.8 7211.2 7762.0 8564.5 8906.7 8946.4 9040.9
03:04:49 INFO - PROCESS | 1408 |
03:04:49 INFO - PROCESS | 1408 | [#3] mootools.html Cycles:180 Average:3916.30 Median:4860.70 stddev:2100.10 (43.2%) stddev-sans-first:2095.82
03:04:49 INFO - PROCESS | 1408 | Values: 1165.8 1178.8 1184.6 1408.5 1461.9 5844.9 6189.4 6203.2 6207.0 6224.1 5261.4 5356.7 5733.5 5911.9 6116.0 1748.5 1751.2 1764.5 1766.4 1770.9 1734.5 1756.4 1760.1 1763.9 1768.3 1527.8 1579.8 1732.9 1760.7 1773.4 1788.2 1793.6 1805.2 1805.7 1810.5 1656.6 1695.6 1696.7 1700.7 1703.8 5894.4 5932.0 5957.0 5993.1 5993.7 3377.6 3555.5 3562.6 3565.5 3566.6 2185.8 2196.9 2244.0 2276.3 2283.7 725.8 754.5 756.0 757.4 759.8 7655.4 7679.1 7686.0 7688.4 7691.8 3091.6 3107.2 3164.1 3165.7 3184.9 6562.8 6660.2 6703.0 6704.3 6740.3 3435.1 3457.8 3464.9 3498.1 3540.3 3140.5 3197.0 3199.5 3201.8 3207.0 2784.8 2822.9 2849.8 2893.8 2899.8 908.5 915.3 920.7 921.8 924.1 579.5 720.0 736.8 756.8 785.0 3886.4 3923.2 3929.0 4019.7 4053.1 788.6 792.3 796.4 798.7 799.3 2841.3 2879.5 2970.4 2997.2 3001.8 6490.0 6946.9 6981.3 7051.3 7073.3 7001.1 7121.9 7133.9 7182.8 7201.4 5612.4 5753.7 5763.9 5774.1 5774.6 4120.0 4164.8 4252.4 4264.7 4265.9 6435.3 6623.9 6645.6 6645.8 6756.1 6650.9 6684.8 6826.9 6837.5 6850.7 5519.0 5550.8 5666.4 5671.8 5690.3 5608.7 5662.0 5692.3 5695.7 5718.1 5018.4 5023.7 5035.0 5038.4 5077.6 4718.5 4941.2 4941.8 5126.4 5131.2 4697.8 4742.0 4747.3 4756.2 4780.2 3392.8 3409.7 3435.8 3447.6 3451.6 3451.5 3513.1 3560.1 3591.7 3616.9
03:04:49 INFO - PROCESS | 1408 |
03:04:49 INFO - PROCESS | 1408 | [#4] prototype.html Cycles:180 Average:3711.60 Median:4226.68 stddev:1124.73 (26.6%) stddev-sans-first:1096.97
03:04:49 INFO - PROCESS | 1408 | Values: 222.0 250.4 250.7 252.4 258.5 4119.0 4217.2 4335.5 4384.0 4392.1 4257.4 4294.5 4300.8 4326.7 4326.8 3177.9 3234.9 3236.5 3240.8 3248.1 3181.2 3237.7 3238.5 3246.7 3253.8 3148.0 3182.5 3201.9 3225.0 3231.5 3200.9 3211.7 3218.3 3228.0 3231.7 2782.8 2840.1 2845.6 2852.9 2860.0 4175.2 4195.8 4214.9 4222.5 4223.6 3835.4 3838.7 3840.6 3841.3 3844.8 4266.4 4447.0 4460.6 4487.0 4507.1 3130.7 3134.5 3141.8 3198.0 3200.4 4620.4 4640.7 4652.3 4680.3 4686.4 3056.9 3176.1 3179.9 3191.2 3194.5 3185.9 3210.2 3212.2 3217.3 3225.4 3646.9 3722.3 3728.2 3735.4 3748.2 4532.3 4535.5 4535.6 4537.3 4543.2 1085.3 1112.7 1116.7 1122.3 1123.5 699.9 702.4 717.6 720.6 726.4 3883.7 3890.4 3894.7 3929.1 3957.6 4167.4 4170.3 4216.6 4229.7 4232.4 3876.8 3881.5 3925.9 3951.7 3956.1 2225.9 2238.4 2245.0 2260.1 2372.0 4400.4 4548.3 4742.7 4786.0 4799.8 4771.9 4773.2 4777.2 4780.5 4788.1 4716.4 4732.2 4807.6 4811.0 4820.5 4524.5 4546.8 4682.6 4691.6 4703.4 4458.0 4532.7 4534.7 4583.3 4590.9 4723.0 4781.5 4784.4 4793.8 4794.9 3172.8 3178.3 3186.0 3198.6 3200.7 4539.9 4605.8 4631.8 4633.5 4636.7 4230.4 4372.7 4384.8 4483.9 4490.0 4450.4 4453.9 4479.3 4490.4 4494.9 4300.2 4319.5 4406.7 4408.7 4411.5 4469.3 4523.0 4531.4 4545.1 4553.2 4151.2 4261.7 4327.4 4380.6 4423.6
03:04:49 INFO - PROCESS | 1408 |
03:04:49 INFO - PROCESS | 1408 | [#5] yui.html Cycles:180 Average:17014.93 Median:23770.24 stddev:24230.20 (101.9%) stddev-sans-first:24266.84
03:04:49 INFO - PROCESS | 1408 | Values: 603.8 612.0 615.7 629.7 651.6 12368.4 12890.2 12993.2 13066.9 13507.6 11320.5 11426.7 11448.9 11469.1 11490.3 636.6 641.2 654.6 657.5 660.4 506.9 519.1 632.4 647.0 658.5 696.8 707.8 708.3 708.7 709.6 700.9 701.9 703.1 703.7 704.9 389.0 394.2 395.2 497.4 514.5 10006.9 10023.8 11328.7 11811.5 11838.1 12561.7 12659.7 12732.4 12794.8 13145.6 10147.4 10221.4 10366.5 10514.0 10700.1 3172.4 3208.1 3258.9 3330.6 3345.6 63312.0 64860.0 65378.7 66674.0 66812.0 9580.8 9634.2 9674.3 9823.1 9986.5 18426.5 18472.2 18534.8 18548.0 18563.7 6570.9 6587.7 6617.3 6726.1 6774.3 3294.5 3429.7 3454.0 3471.1 3516.8 2394.8 2484.5 2498.8 2503.5 2508.4 399.0 401.0 402.1 409.8 410.3 561.7 580.5 599.3 615.5 620.0 7883.2 7884.8 7928.9 8159.3 8281.4 467.9 518.0 592.3 592.9 632.0 3814.4 4049.7 4155.3 4165.8 4282.7 65239.0 94227.0 94446.5 95305.5 96114.0 81788.6 82676.0 83677.0 83838.0 85568.0 39727.0 62831.0 73325.6 73388.3 74676.3 7872.6 7970.7 8041.4 8104.4 8307.3 41426.8 42276.0 43257.8 43542.3 44799.3 58439.4 60392.4 60412.4 61141.4 61716.0 10633.4 10837.5 10847.8 10956.3 10999.7 11857.9 11976.2 12211.3 12270.8 12290.2 11423.4 11473.5 11488.4 11597.3 11851.3 11660.8 11852.5 12014.6 12148.8 12506.2 10839.8 11347.5 11478.5 11506.4 11986.8 9854.9 10041.5 10060.5 10602.0 10675.6 9858.8 9864.4 9899.8 9984.5 10025.7
03:04:49 INFO - PROCESS | 1408 | -------- Summary: end --------
03:04:49 INFO - PROCESS | 1408 |
03:04:50 INFO - TEST-INFO | 1408: exit 0
03:04:51 INFO - TEST-OK | dromaeo_css | took 1124259ms
03:04:51 INFO - TEST-START | kraken
03:04:51 INFO - operating with platform_type : w7_
03:04:51 INFO - Initialising browser for kraken test...
03:04:51 INFO - TEST-INFO | started process 2836 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmp9qr0em\profile http://localhost:49893/getInfo.html)
03:04:55 INFO - PROCESS | 2836 | __metrics Screen width/height:1600/1200
03:04:55 INFO - PROCESS | 2836 | colorDepth:24
03:04:55 INFO - PROCESS | 2836 | Browser inner width/height: 1010/674
03:04:55 INFO - PROCESS | 2836 | __metrics
03:04:56 INFO - PROCESS | 2836 | 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"
03:04:56 INFO - PROCESS | 2836 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
03:04:57 INFO - PROCESS | 2836 | console.error:
03:04:57 INFO - PROCESS | 2836 | Message: Error: Connection closed before committing the transaction.
03:04:57 INFO - PROCESS | 2836 | Stack:
03:04:57 INFO - PROCESS | 2836 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:937:23
03:04:57 INFO - PROCESS | 2836 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7
03:04:57 INFO - PROCESS | 2836 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11
03:04:57 INFO - PROCESS | 2836 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7
03:04:57 INFO - PROCESS | 2836 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7
03:04:57 INFO - PROCESS | 2836 | TaskImpl_run@resource://gre/modules/Task.jsm:324:13
03:04:57 INFO - PROCESS | 2836 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9
03:04:57 INFO - PROCESS | 2836 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15
03:04:57 INFO - PROCESS | 2836 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5
03:04:57 INFO - PROCESS | 2836 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29
03:04:57 INFO - PROCESS | 2836 | Barrier/this.client.addBlocker/promise13.2%
03:05:57 INFO - TinderboxPrint: I/O read bytes / time
80,709,120 / 1,286,930
03:05:57 INFO - TinderboxPrint: I/O write bytes / time
452,433,920 / 3,517,160
03:05:57 INFO - TinderboxPrint: CPU idle
8,303.0 (86.8%)
03:05:57 INFO - TinderboxPrint: CPU user
1,225.3 (12.8%)
03:05:57 INFO - install - Wall time: 3s; CPU: 11.0%; Read bytes: 32768; Write bytes: 34704896; Read time: 1840; Write time: 215630
03:05:57 INFO - run-tests - Wall time: 1193s; CPU: 13.0%; Read bytes: 80676352; Write bytes: 378901504; Read time: 1285090; Write time: 2820940
03:05:58 INFO - Running post-run listener: _upload_blobber_files
03:05:58 INFO - Blob upload gear active.
03:05:58 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir.
03:05:58 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
03:05:58 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-Non-PGO', '-d', 'C:\\slave\\test\\build\\blobber_upload_dir', '--output-manifest', 'C:\\slave\\test\\build\\uploaded_files.json']
03:05:58 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-Non-PGO -d C:\slave\test\build\blobber_upload_dir --output-manifest C:\slave\test\build\uploaded_files.json
03:05:58 INFO - (blobuploader) - INFO - Open directory for files ...
03:05:58 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log ...
03:05:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
03:05:58 INFO - (blobuploader) - INFO - Uploading, attempt #1.
03:05:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded
03:05:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
03:05:59 INFO - (blobuploader) - INFO - Done attempting.
03:05:59 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log ...
03:05:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
03:05:59 INFO - (blobuploader) - INFO - Uploading, attempt #1.
03:06:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded
03:06:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
03:06:00 INFO - (blobuploader) - INFO - Done attempting.
03:06:00 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ...
03:06:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
03:06:00 INFO - (blobuploader) - INFO - Uploading, attempt #1.
03:06:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
03:06:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
03:06:08 INFO - (blobuploader) - INFO - Done attempting.
03:06:08 INFO - (blobuploader) - INFO - Iteration through files over.
03:06:08 INFO - Return code: 0
03:06:08 INFO - rmtree: C:\slave\test\build\uploaded_files.json
03:06:08 INFO - Using _rmtree_windows ...
03:06:08 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1
03:06:08 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/62478576f85e577ebdec96ff2cbe61655bb3907af14b8219e958d3760ec325e3c084c80d762ad204b6a8e0c31878a3ec00543eb3745c750b1805ccbcc6adfd93", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/cc12745491320334305ce081d2a46c3edbccaaa9da623ef836a84ffa2fb28c66e4fc685aeb6ef509a3dc1432709ea0d2758f1b6b40d7a1d18277007235035c19", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/0bc5bc41e006ce652bff4fc5b4152c96927c0d3d361fb099bf6bc91ef147e8b5d3c8ba3b9b592c2fb3e459b1388e3a26ed2be03e210d12fbf92770fc61836652"}
03:06:08 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files
03:06:08 INFO - Writing to file C:\slave\test\properties\blobber_files
03:06:08 INFO - Contents:
03:06:08 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/62478576f85e577ebdec96ff2cbe61655bb3907af14b8219e958d3760ec325e3c084c80d762ad204b6a8e0c31878a3ec00543eb3745c750b1805ccbcc6adfd93", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/cc12745491320334305ce081d2a46c3edbccaaa9da623ef836a84ffa2fb28c66e4fc685aeb6ef509a3dc1432709ea0d2758f1b6b40d7a1d18277007235035c19", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/0bc5bc41e006ce652bff4fc5b4152c96927c0d3d361fb099bf6bc91ef147e8b5d3c8ba3b9b592c2fb3e459b1388e3a26ed2be03e210d12fbf92770fc61836652"}
03:06:08 INFO - Running post-run listener: copy_logs_to_upload_dir
03:06:08 INFO - Copying logs to upload dir...
03:06:08 INFO - mkdir: C:\slave\test\build\upload\logs
03:06:08 INFO - Copying logs to upload dir...
03:06:08 INFO - Using _rmtree_windows ...
03:06:08 INFO - Using _rmtree_windows ...
03:06:08 INFO - Using _rmtree_windows ...
03:06:08 INFO - Using _rmtree_windows ...
03:06:08 INFO - Using _rmtree_windows ...
03:06:08 INFO - Using _rmtree_windows ...
03:06:08 INFO - Using _rmtree_windows ...
program finished with exit code 0
elapsedTime=1457.628000
========= master_lag: 0.02 =========
========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 17 secs) (at 2016-09-28 03:06:08.437872) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-28 03:06:08.443661) =========
'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-140
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-140
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-140
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-Non-PGO/sha512/62478576f85e577ebdec96ff2cbe61655bb3907af14b8219e958d3760ec325e3c084c80d762ad204b6a8e0c31878a3ec00543eb3745c750b1805ccbcc6adfd93", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/cc12745491320334305ce081d2a46c3edbccaaa9da623ef836a84ffa2fb28c66e4fc685aeb6ef509a3dc1432709ea0d2758f1b6b40d7a1d18277007235035c19", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/0bc5bc41e006ce652bff4fc5b4152c96927c0d3d361fb099bf6bc91ef147e8b5d3c8ba3b9b592c2fb3e459b1388e3a26ed2be03e210d12fbf92770fc61836652"}
build_url:https://queue.taskcluster.net/v1/task/dFMBNftxRKKCcOFSw43h-g/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/dFMBNftxRKKCcOFSw43h-g/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-Non-PGO/sha512/62478576f85e577ebdec96ff2cbe61655bb3907af14b8219e958d3760ec325e3c084c80d762ad204b6a8e0c31878a3ec00543eb3745c750b1805ccbcc6adfd93", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/cc12745491320334305ce081d2a46c3edbccaaa9da623ef836a84ffa2fb28c66e4fc685aeb6ef509a3dc1432709ea0d2758f1b6b40d7a1d18277007235035c19", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/0bc5bc41e006ce652bff4fc5b4152c96927c0d3d361fb099bf6bc91ef147e8b5d3c8ba3b9b592c2fb3e459b1388e3a26ed2be03e210d12fbf92770fc61836652"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-28 03:06:08.560971) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 03:06:08.561411) =========
'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-140
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-140
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-140
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-28 03:06:08.676680) =========
========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-09-28 03:06:08.676991) =========
========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-09-28 03:06:09.213327) =========
========= Total master_lag: 0.24 =========