builder: fx-team_win7_ix_test-dromaeojs-pgo
slave: t-w732-ix-128
starttime: 1474649731.37
results: success (0)
buildid: 20160923063008
builduid: d67bb725fca74bb783ebe531911d00ac
revision: 2e46d0c12db68401969a817832329187da8add06
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-23 09:55:31.374957) =========
master: http://buildbot-master119.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-23 09:55:31.375390) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-23 09:55:31.375685) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-23 09:55:31.594304) =========
========= Started 'bash -c ...' (results: 0, elapsed: 6 secs) (at 2016-09-23 09:55:31.594795) =========
'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-128
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-128
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-128
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
--09:55:32-- 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.
HTTP request sent, awaiting response... 200 Script output follows
Length: 12,141 (12K) [text/x-python]
0K .......... . 100% 8.63 MB/s
09:55:33 (8.63 MB/s) - `archiver_client.py' saved [12141/12141]
program finished with exit code 0
elapsedTime=1.782000
========= master_lag: 4.70 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 6 secs) (at 2016-09-23 09:55:38.080594) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 15 secs) (at 2016-09-23 09:55:38.080906) =========
'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-128
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-128
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-128
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
program finished with exit code 0
elapsedTime=15.101000
========= master_lag: 0.02 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 15 secs) (at 2016-09-23 09:55:53.206231) =========
========= Started 'bash -c ...' (results: 0, elapsed: 6 secs) (at 2016-09-23 09:55:53.206585) =========
'bash' '-c' u'python archiver_client.py mozharness --repo integration/fx-team --rev 2e46d0c12db68401969a817832329187da8add06 --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 2e46d0c12db68401969a817832329187da8add06 --destination scripts --debug']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-128
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-128
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-128
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-23 09:55:53,674 truncating revision to first 12 chars
2016-09-23 09:55:53,674 Setting DEBUG logging.
2016-09-23 09:55:53,674 attempt 1/10
2016-09-23 09:55:53,674 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/2e46d0c12db6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2016-09-23 09:55:55,154 unpacking tar archive at: fx-team-2e46d0c12db6/testing/mozharness/
2016-09-23 09:55:55,305 skipping symlink on windows: configs/single_locale/linux32.py
program finished with exit code 0
elapsedTime=2.342000
========= master_lag: 3.96 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 6 secs) (at 2016-09-23 09:55:59.512126) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-23 09:55:59.512450) =========
script_repo_revision: 2e46d0c12db68401969a817832329187da8add06
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-23 09:55:59.512854) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-23 09:55:59.513132) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-23 09:55:59.552538) =========
========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 41 secs) (at 2016-09-23 09:55:59.552891) =========
'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Fx-Team' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Fx-Team'
in dir C:\slave\test\. (timeout 3600 secs) (maxTime 7200 secs)
watching logfiles {}
argv: ['c:/mozilla-build/python27/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-128
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-128
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-128
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
09:56:00 INFO - MultiFileLogger online at 20160923 09:56:00 in C:\slave\test
09:56:00 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team
09:56:00 INFO - Dumping config to C:\slave\test\logs\localconfig.json.
09:56:00 INFO - {'append_to_log': False,
09:56:00 INFO - 'base_work_dir': 'C:\\slave\\test',
09:56:00 INFO - 'blob_upload_branch': 'Fx-Team',
09:56:00 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt',
09:56:00 INFO - 'branch': 'Fx-Team',
09:56:00 INFO - 'buildbot_json_path': 'buildprops.json',
09:56:00 INFO - 'config_files': ('talos/windows_config.py',),
09:56:00 INFO - 'default_actions': ('clobber',
09:56:00 INFO - 'read-buildbot-config',
09:56:00 INFO - 'download-and-extract',
09:56:00 INFO - 'populate-webroot',
09:56:00 INFO - 'create-virtualenv',
09:56:00 INFO - 'install',
09:56:00 INFO - 'run-tests'),
09:56:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
09:56:00 INFO - 'download_minidump_stackwalk': True,
09:56:00 INFO - 'download_symbols': 'ondemand',
09:56:00 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python',
09:56:00 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'),
09:56:00 INFO - 'hg': 'c:/mozilla-build/hg/hg',
09:56:00 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python',
09:56:00 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'),
09:56:00 INFO - 'python': 'c:/mozilla-build/python27/python.exe',
09:56:00 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe',
09:56:00 INFO - 'C:/mozilla-build/tooltool.py'),
09:56:00 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe',
09:56:00 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')},
09:56:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
09:56:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
09:56:00 INFO - 'installer_path': 'installer.exe',
09:56:00 INFO - 'log_level': 'info',
09:56:00 INFO - 'log_name': 'talos',
09:56:00 INFO - 'log_to_console': True,
09:56:00 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe',
09:56:00 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe',
09:56:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest',
09:56:00 INFO - 'opt_config_files': (),
09:56:00 INFO - 'pip_index': False,
09:56:00 INFO - 'sps_profile': False,
09:56:00 INFO - 'sps_profile_interval': 0,
09:56:00 INFO - 'suite': 'dromaeojs',
09:56:00 INFO - 'system_bits': '32',
09:56:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
09:56:00 INFO - 'title': 'T-W732-IX-128',
09:56:00 INFO - 'use_talos_json': True,
09:56:00 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'),
09:56:00 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv',
09:56:00 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll',
09:56:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
09:56:00 INFO - 'work_dir': 'build'}
09:56:00 INFO - [mozharness: 2016-09-23 16:56:00.248000Z] Running clobber step.
09:56:00 INFO - Running pre-action listener: _resource_record_pre_action
09:56:00 INFO - Running main action method: clobber
09:56:00 INFO - rmtree: C:\slave\test\build
09:56:00 INFO - Using _rmtree_windows ...
09:56:00 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1
09:57:59 INFO - Running post-action listener: _resource_record_post_action
09:57:59 INFO - [mozharness: 2016-09-23 16:57:59.767000Z] Finished clobber step (success)
09:57:59 INFO - [mozharness: 2016-09-23 16:57:59.767000Z] Running read-buildbot-config step.
09:57:59 INFO - Running pre-action listener: _resource_record_pre_action
09:57:59 INFO - Running main action method: read_buildbot_config
09:57:59 INFO - Using buildbot properties:
09:57:59 INFO - {
09:57:59 INFO - "project": "",
09:57:59 INFO - "product": "firefox",
09:57:59 INFO - "script_repo_revision": "production",
09:57:59 INFO - "builddir": "fx-team_win7_ix_test-dromaeojs",
09:57:59 INFO - "repository": "",
09:57:59 INFO - "buildername": "Windows 7 32-bit fx-team pgo talos dromaeojs",
09:57:59 INFO - "buildid": "20160923063008",
09:57:59 INFO - "pgo_build": "True",
09:57:59 INFO - "basedir": "C:\\slave\\test",
09:57:59 INFO - "buildnumber": 23,
09:57:59 INFO - "slavename": "t-w732-ix-128",
09:57:59 INFO - "master": "http://buildbot-master119.bb.releng.scl3.mozilla.com:8201/",
09:57:59 INFO - "platform": "win7_ix",
09:57:59 INFO - "scheduler": "tests-fx-team-win32-pgo-talos",
09:57:59 INFO - "branch": "fx-team",
09:57:59 INFO - "repo_path": "integration/fx-team",
09:57:59 INFO - "revision": "2e46d0c12db68401969a817832329187da8add06",
09:57:59 INFO - "stage_platform": "win32-pgo",
09:57:59 INFO - "builduid": "d67bb725fca74bb783ebe531911d00ac",
09:57:59 INFO - "slavebuilddir": "test"
09:57:59 INFO - }
09:57:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/WQ38qFB_S9mAMfmBMUN81Q/artifacts/public/build/firefox-52.0a1.en-US.win32.zip.
09:57:59 INFO - Running post-action listener: _resource_record_post_action
09:57:59 INFO - [mozharness: 2016-09-23 16:57:59.771000Z] Finished read-buildbot-config step (success)
09:57:59 INFO - [mozharness: 2016-09-23 16:57:59.771000Z] Running download-and-extract step.
09:57:59 INFO - Running pre-action listener: _resource_record_pre_action
09:57:59 INFO - Running main action method: download_and_extract
09:57:59 INFO - mkdir: C:\slave\test\build\tests
09:57:59 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')]}
09:57:59 INFO - trying https://queue.taskcluster.net/v1/task/WQ38qFB_S9mAMfmBMUN81Q/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json
09:57:59 INFO - Downloading https://queue.taskcluster.net/v1/task/WQ38qFB_S9mAMfmBMUN81Q/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
09:57:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WQ38qFB_S9mAMfmBMUN81Q/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
09:58:00 INFO - Downloaded 1218 bytes.
09:58:00 INFO - Reading from file C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json
09:58:00 INFO - Using the following test package requirements:
09:58:00 INFO - {u'common': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
09:58:00 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
09:58:00 INFO - u'firefox-52.0a1.en-US.win32.cppunittest.tests.zip'],
09:58:00 INFO - u'gtest': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
09:58:00 INFO - u'jittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
09:58:00 INFO - u'jsshell-win32.zip'],
09:58:00 INFO - u'mochitest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
09:58:00 INFO - u'firefox-52.0a1.en-US.win32.mochitest.tests.zip'],
09:58:00 INFO - u'mozbase': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
09:58:00 INFO - u'reftest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
09:58:00 INFO - u'firefox-52.0a1.en-US.win32.reftest.tests.zip'],
09:58:00 INFO - u'talos': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
09:58:00 INFO - u'firefox-52.0a1.en-US.win32.talos.tests.zip'],
09:58:00 INFO - u'web-platform': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
09:58:00 INFO - u'firefox-52.0a1.en-US.win32.web-platform.tests.zip'],
09:58:00 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
09:58:00 INFO - u'firefox-52.0a1.en-US.win32.xpcshell.tests.zip']}
09:58:00 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip'] for test suite category: common
09:58:00 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/WQ38qFB_S9mAMfmBMUN81Q/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip
09:58:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WQ38qFB_S9mAMfmBMUN81Q/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1
09:58:00 INFO - Fetch https://queue.taskcluster.net/v1/task/WQ38qFB_S9mAMfmBMUN81Q/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory
09:58:01 INFO - Expected file size: 17291325
09:58:29 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
09:58:29 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/WQ38qFB_S9mAMfmBMUN81Q/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip
09:58:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WQ38qFB_S9mAMfmBMUN81Q/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1
09:58:29 INFO - Fetch https://queue.taskcluster.net/v1/task/WQ38qFB_S9mAMfmBMUN81Q/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory
09:58:30 INFO - Expected file size: 17291325
09:58:44 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/WQ38qFB_S9mAMfmBMUN81Q/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip
09:58:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WQ38qFB_S9mAMfmBMUN81Q/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip'}, attempt #1
09:58:44 INFO - Fetch https://queue.taskcluster.net/v1/task/WQ38qFB_S9mAMfmBMUN81Q/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip into memory
09:58:45 INFO - Expected file size: 14414059
09:58:47 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')]}
09:58:47 INFO - trying https://queue.taskcluster.net/v1/task/WQ38qFB_S9mAMfmBMUN81Q/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
09:58:47 INFO - Downloading https://queue.taskcluster.net/v1/task/WQ38qFB_S9mAMfmBMUN81Q/artifacts/public/build/firefox-52.0a1.en-US.win32.zip to C:\slave\test\build\firefox-52.0a1.en-US.win32.zip
09:58:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WQ38qFB_S9mAMfmBMUN81Q/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
09:58:48 INFO - Downloaded 54786539 bytes.
09:58:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WQ38qFB_S9mAMfmBMUN81Q/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
09:58:48 INFO - mkdir: C:\slave\test\properties
09:58:49 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url
09:58:49 INFO - Writing to file C:\slave\test\properties\build_url
09:58:49 INFO - Contents:
09:58:49 INFO - build_url:https://queue.taskcluster.net/v1/task/WQ38qFB_S9mAMfmBMUN81Q/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
09:58:49 INFO - Running post-action listener: _resource_record_post_action
09:58:49 INFO - Running post-action listener: set_extra_try_arguments
09:58:49 INFO - [mozharness: 2016-09-23 16:58:49.716000Z] Finished download-and-extract step (success)
09:58:49 INFO - [mozharness: 2016-09-23 16:58:49.716000Z] Running populate-webroot step.
09:58:49 INFO - Running pre-action listener: _resource_record_pre_action
09:58:49 INFO - Running main action method: populate_webroot
09:58:49 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']},
09:58:49 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']},
09:58:49 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome',
09:58:49 INFO - u'--tppagecycles',
09:58:49 INFO - u'7'],
09:58:49 INFO - u'tests': [u'tsvgm']}},
09:58:49 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'],
09:58:49 INFO - u'tests': [u'tresize', u'tcanvasmark']},
09:58:49 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']},
09:58:49 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'],
09:58:49 INFO - u'tests': [u'dromaeo_css', u'kraken']},
09:58:49 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']},
09:58:49 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
09:58:49 INFO - u'talos_options': [u'--disable-e10s'],
09:58:49 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
09:58:49 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
09:58:49 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
09:58:49 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
09:58:49 INFO - u'talos_options': [u'--disable-e10s'],
09:58:49 INFO - u'tests': [u'damp', u'tps']},
09:58:49 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
09:58:49 INFO - u'tests': [u'damp', u'tps']},
09:58:49 INFO - u'g3': {u'talos_options': [u'--disable-e10s'],
09:58:49 INFO - u'tests': [u'dromaeo_dom']},
09:58:49 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']},
09:58:49 INFO - u'g4': {u'talos_options': [u'--disable-e10s'],
09:58:49 INFO - u'tests': [u'basic_compositor_video']},
09:58:49 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']},
09:58:49 INFO - u'other': {u'talos_options': [u'--disable-e10s'],
09:58:49 INFO - u'tests': [u'a11yr',
09:58:49 INFO - u'ts_paint',
09:58:49 INFO - u'tpaint',
09:58:49 INFO - u'sessionrestore',
09:58:49 INFO - u'sessionrestore_no_auto_restore',
09:58:49 INFO - u'tabpaint']},
09:58:49 INFO - u'other-e10s': {u'tests': [u'a11yr',
09:58:49 INFO - u'ts_paint',
09:58:49 INFO - u'tpaint',
09:58:49 INFO - u'sessionrestore',
09:58:49 INFO - u'sessionrestore_no_auto_restore',
09:58:49 INFO - u'tabpaint']},
09:58:49 INFO - u'other-e10s_l64': {u'tests': [u'a11yr',
09:58:49 INFO - u'ts_paint',
09:58:49 INFO - u'tpaint',
09:58:49 INFO - u'sessionrestore',
09:58:49 INFO - u'sessionrestore_no_auto_restore',
09:58:49 INFO - u'tabpaint']},
09:58:49 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr',
09:58:49 INFO - u'ts_paint',
09:58:49 INFO - u'tpaint',
09:58:49 INFO - u'sessionrestore',
09:58:49 INFO - u'sessionrestore_no_auto_restore',
09:58:49 INFO - u'tabpaint']},
09:58:49 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'],
09:58:49 INFO - u'tests': [u'a11yr',
09:58:49 INFO - u'ts_paint',
09:58:49 INFO - u'tpaint',
09:58:49 INFO - u'sessionrestore',
09:58:49 INFO - u'sessionrestore_no_auto_restore',
09:58:49 INFO - u'tabpaint']},
09:58:49 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'],
09:58:49 INFO - u'tests': [u'a11yr',
09:58:49 INFO - u'ts_paint',
09:58:49 INFO - u'tpaint',
09:58:49 INFO - u'sessionrestore',
09:58:49 INFO - u'sessionrestore_no_auto_restore',
09:58:49 INFO - u'tabpaint']},
09:58:49 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'],
09:58:49 INFO - u'tests': [u'tsvgx',
09:58:49 INFO - u'tsvgr_opacity',
09:58:49 INFO - u'tart',
09:58:49 INFO - u'tscrollx',
09:58:49 INFO - u'cart']},
09:58:49 INFO - u'svgr-e10s': {u'tests': [u'tsvgx',
09:58:49 INFO - u'tsvgr_opacity',
09:58:49 INFO - u'tart',
09:58:49 INFO - u'tscrollx',
09:58:49 INFO - u'cart']},
09:58:49 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
09:58:49 INFO - u'talos_options': [u'--disable-e10s'],
09:58:49 INFO - u'tests': [u'tp5o']},
09:58:49 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
09:58:49 INFO - u'tests': [u'tp5o']},
09:58:49 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
09:58:49 INFO - u'talos_options': [u'--disable-e10s',
09:58:49 INFO - u'--xperf_path',
09:58:49 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
09:58:49 INFO - u'tests': [u'tp5n']},
09:58:49 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
09:58:49 INFO - u'talos_options': [u'--xperf_path',
09:58:49 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
09:58:49 INFO - u'tests': [u'tp5n']}},
09:58:49 INFO - u'talos.zip': {u'path': u'',
09:58:49 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}}
09:58:49 INFO - Running post-action listener: _resource_record_post_action
09:58:49 INFO - [mozharness: 2016-09-23 16:58:49.746000Z] Finished populate-webroot step (success)
09:58:49 INFO - [mozharness: 2016-09-23 16:58:49.747000Z] Running create-virtualenv step.
09:58:49 INFO - Running pre-action listener: _resource_record_pre_action
09:58:49 INFO - Running main action method: create_virtualenv
09:58:49 INFO - Creating virtualenv C:\slave\test\build/venv
09:58:49 INFO - mkdir: C:\slave\test\build\venv\Scripts
09:58:49 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll
09:58:49 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'!
09:58:49 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
09:58:49 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv
09:58:57 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe
09:59:02 INFO - Installing distribute......................................................................................................................................................................................done.
09:59:04 INFO - Return code: 0
09:59:04 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv
09:59:04 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')]}
09:59:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
09:59:04 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')]}
09:59:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
09:59:04 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 0x017E1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01885E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0178B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01892E90>, '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 0x012AA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01954520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-128', 'USERDOMAIN': 'T-W732-IX-128', '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-128', '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
09:59:04 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
09:59:04 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
09:59:04 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
09:59:04 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
09:59:04 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
09:59:04 INFO - 'COMPUTERNAME': 'T-W732-IX-128',
09:59:04 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
09:59:04 INFO - 'CYGWINBASE': 'C:\\cygwin',
09:59:04 INFO - 'DCLOCATION': 'SCL3',
09:59:04 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
09:59:04 INFO - 'FP_NO_HOST_CHECK': 'NO',
09:59:04 INFO - 'HOMEDRIVE': 'C:',
09:59:04 INFO - 'HOMEPATH': '\\Users\\cltbld',
09:59:04 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
09:59:04 INFO - 'KTS_VERSION': '1.19c',
09:59:04 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
09:59:04 INFO - 'LOGONSERVER': '\\\\T-W732-IX-128',
09:59:04 INFO - 'MONDIR': 'C:\\Monitor_config\\',
09:59:04 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
09:59:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
09:59:04 INFO - 'MOZ_NO_REMOTE': '1',
09:59:04 INFO - 'NO_EM_RESTART': '1',
09:59:04 INFO - 'NUMBER_OF_PROCESSORS': '8',
09:59:04 INFO - 'OS': 'Windows_NT',
09:59:04 INFO - 'OURDRIVE': 'C:',
09:59:04 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;',
09:59:04 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
09:59:04 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
09:59:04 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
09:59:04 INFO - 'PROCESSOR_LEVEL': '6',
09:59:04 INFO - 'PROCESSOR_REVISION': '1e05',
09:59:04 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
09:59:04 INFO - 'PROGRAMFILES': 'C:\\Program Files',
09:59:04 INFO - 'PROMPT': '$P$G',
09:59:04 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
09:59:04 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
09:59:04 INFO - 'PUBLIC': 'C:\\Users\\Public',
09:59:04 INFO - 'PWD': 'C:\\slave\\test',
09:59:04 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
09:59:04 INFO - 'SLAVEDIR': 'C:\\slave\\',
09:59:04 INFO - 'SYSTEMDRIVE': 'C:',
09:59:04 INFO - 'SYSTEMROOT': 'C:\\windows',
09:59:04 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:59:04 INFO - 'TEST1': 'testie',
09:59:04 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:59:04 INFO - 'USERDOMAIN': 'T-W732-IX-128',
09:59:04 INFO - 'USERNAME': 'cltbld',
09:59:04 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
09:59:04 INFO - 'WINDIR': 'C:\\windows',
09:59:04 INFO - 'WINDOWS_TRACING_FLAGS': '3',
09:59:04 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
09:59:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
09:59:04 INFO - Ignoring indexes: http://pypi.python.org/simple/
09:59:04 INFO - Downloading/unpacking pip>=1.5
09:59:09 INFO - Running setup.py egg_info for package pip
09:59:09 INFO - warning: no files found matching 'pip\cacert.pem'
09:59:09 INFO - warning: no files found matching '*.html' under directory 'docs'
09:59:09 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
09:59:09 INFO - no previously-included directories found matching 'docs\_build\_sources'
09:59:09 INFO - Installing collected packages: pip
09:59:09 INFO - Found existing installation: pip 0.8.2
09:59:09 INFO - Uninstalling pip:
09:59:09 INFO - Successfully uninstalled pip
09:59:09 INFO - Running setup.py install for pip
09:59:09 INFO - warning: no files found matching 'pip\cacert.pem'
09:59:09 INFO - warning: no files found matching '*.html' under directory 'docs'
09:59:09 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
09:59:09 INFO - no previously-included directories found matching 'docs\_build\_sources'
09:59:09 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts
09:59:09 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts
09:59:09 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts
09:59:09 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts
09:59:09 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts
09:59:09 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts
09:59:09 INFO - Successfully installed pip
09:59:09 INFO - Cleaning up...
09:59:09 INFO - Return code: 0
09:59:09 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv
09:59:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
09:59:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
09:59:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
09:59:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
09:59:09 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x017E1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01885E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0178B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01892E90>, '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 0x012AA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01954520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-128', 'USERDOMAIN': 'T-W732-IX-128', '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-128', '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
09:59:09 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in C:\slave\test\build
09:59:09 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1
09:59:09 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
09:59:09 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
09:59:09 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
09:59:09 INFO - 'COMPUTERNAME': 'T-W732-IX-128',
09:59:09 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
09:59:09 INFO - 'CYGWINBASE': 'C:\\cygwin',
09:59:09 INFO - 'DCLOCATION': 'SCL3',
09:59:09 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
09:59:09 INFO - 'FP_NO_HOST_CHECK': 'NO',
09:59:09 INFO - 'HOMEDRIVE': 'C:',
09:59:09 INFO - 'HOMEPATH': '\\Users\\cltbld',
09:59:09 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
09:59:09 INFO - 'KTS_VERSION': '1.19c',
09:59:09 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
09:59:09 INFO - 'LOGONSERVER': '\\\\T-W732-IX-128',
09:59:09 INFO - 'MONDIR': 'C:\\Monitor_config\\',
09:59:09 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
09:59:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
09:59:09 INFO - 'MOZ_NO_REMOTE': '1',
09:59:09 INFO - 'NO_EM_RESTART': '1',
09:59:09 INFO - 'NUMBER_OF_PROCESSORS': '8',
09:59:09 INFO - 'OS': 'Windows_NT',
09:59:09 INFO - 'OURDRIVE': 'C:',
09:59:09 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
09:59:09 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
09:59:09 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
09:59:09 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
09:59:09 INFO - 'PROCESSOR_LEVEL': '6',
09:59:09 INFO - 'PROCESSOR_REVISION': '1e05',
09:59:09 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
09:59:09 INFO - 'PROGRAMFILES': 'C:\\Program Files',
09:59:09 INFO - 'PROMPT': '$P$G',
09:59:09 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
09:59:09 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
09:59:09 INFO - 'PUBLIC': 'C:\\Users\\Public',
09:59:09 INFO - 'PWD': 'C:\\slave\\test',
09:59:09 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
09:59:09 INFO - 'SLAVEDIR': 'C:\\slave\\',
09:59:09 INFO - 'SYSTEMDRIVE': 'C:',
09:59:09 INFO - 'SYSTEMROOT': 'C:\\windows',
09:59:09 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:59:09 INFO - 'TEST1': 'testie',
09:59:09 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:59:09 INFO - 'USERDOMAIN': 'T-W732-IX-128',
09:59:09 INFO - 'USERNAME': 'cltbld',
09:59:09 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
09:59:09 INFO - 'WINDIR': 'C:\\windows',
09:59:09 INFO - 'WINDOWS_TRACING_FLAGS': '3',
09:59:09 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
09:59:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
09:59:11 INFO - Ignoring indexes: https://pypi.python.org/simple/
09:59:11 INFO - Downloading/unpacking psutil>=3.1.1
09:59:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
09:59:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
09:59:12 INFO - Installing collected packages: psutil
09:59:12 INFO - Successfully installed psutil
09:59:12 INFO - Cleaning up...
09:59:12 INFO - Return code: 0
09:59:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv
09:59:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
09:59:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
09:59:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
09:59:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
09:59:12 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 0x017E1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01885E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0178B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01892E90>, '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 0x012AA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01954520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-128', 'USERDOMAIN': 'T-W732-IX-128', '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-128', '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
09:59:12 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
09:59:12 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
09:59:12 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
09:59:12 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
09:59:12 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
09:59:12 INFO - 'COMPUTERNAME': 'T-W732-IX-128',
09:59:12 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
09:59:12 INFO - 'CYGWINBASE': 'C:\\cygwin',
09:59:12 INFO - 'DCLOCATION': 'SCL3',
09:59:12 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
09:59:12 INFO - 'FP_NO_HOST_CHECK': 'NO',
09:59:12 INFO - 'HOMEDRIVE': 'C:',
09:59:12 INFO - 'HOMEPATH': '\\Users\\cltbld',
09:59:12 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
09:59:12 INFO - 'KTS_VERSION': '1.19c',
09:59:12 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
09:59:12 INFO - 'LOGONSERVER': '\\\\T-W732-IX-128',
09:59:12 INFO - 'MONDIR': 'C:\\Monitor_config\\',
09:59:12 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
09:59:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
09:59:12 INFO - 'MOZ_NO_REMOTE': '1',
09:59:12 INFO - 'NO_EM_RESTART': '1',
09:59:12 INFO - 'NUMBER_OF_PROCESSORS': '8',
09:59:12 INFO - 'OS': 'Windows_NT',
09:59:12 INFO - 'OURDRIVE': 'C:',
09:59:12 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;',
09:59:12 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
09:59:12 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
09:59:12 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
09:59:12 INFO - 'PROCESSOR_LEVEL': '6',
09:59:12 INFO - 'PROCESSOR_REVISION': '1e05',
09:59:12 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
09:59:12 INFO - 'PROGRAMFILES': 'C:\\Program Files',
09:59:12 INFO - 'PROMPT': '$P$G',
09:59:12 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
09:59:12 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
09:59:12 INFO - 'PUBLIC': 'C:\\Users\\Public',
09:59:12 INFO - 'PWD': 'C:\\slave\\test',
09:59:12 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
09:59:12 INFO - 'SLAVEDIR': 'C:\\slave\\',
09:59:12 INFO - 'SYSTEMDRIVE': 'C:',
09:59:12 INFO - 'SYSTEMROOT': 'C:\\windows',
09:59:12 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:59:12 INFO - 'TEST1': 'testie',
09:59:12 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:59:12 INFO - 'USERDOMAIN': 'T-W732-IX-128',
09:59:12 INFO - 'USERNAME': 'cltbld',
09:59:12 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
09:59:12 INFO - 'WINDIR': 'C:\\windows',
09:59:12 INFO - 'WINDOWS_TRACING_FLAGS': '3',
09:59:12 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
09:59:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
09:59:13 INFO - Ignoring indexes: https://pypi.python.org/simple/
09:59:13 INFO - Downloading/unpacking mozsystemmonitor==0.3
09:59:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
09:59:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
09:59:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz
09:59:14 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor
09:59:14 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)
09:59:14 INFO - Installing collected packages: mozsystemmonitor
09:59:14 INFO - Running setup.py install for mozsystemmonitor
09:59:14 INFO - Successfully installed mozsystemmonitor
09:59:14 INFO - Cleaning up...
09:59:14 INFO - Return code: 0
09:59:14 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv
09:59:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
09:59:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
09:59:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
09:59:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
09:59:14 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x017E1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01885E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0178B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01892E90>, '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 0x012AA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01954520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-128', 'USERDOMAIN': 'T-W732-IX-128', '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-128', '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
09:59:14 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in C:\slave\test\build
09:59:14 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1
09:59:14 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
09:59:14 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
09:59:14 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
09:59:14 INFO - 'COMPUTERNAME': 'T-W732-IX-128',
09:59:14 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
09:59:14 INFO - 'CYGWINBASE': 'C:\\cygwin',
09:59:14 INFO - 'DCLOCATION': 'SCL3',
09:59:14 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
09:59:14 INFO - 'FP_NO_HOST_CHECK': 'NO',
09:59:14 INFO - 'HOMEDRIVE': 'C:',
09:59:14 INFO - 'HOMEPATH': '\\Users\\cltbld',
09:59:14 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
09:59:14 INFO - 'KTS_VERSION': '1.19c',
09:59:14 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
09:59:14 INFO - 'LOGONSERVER': '\\\\T-W732-IX-128',
09:59:14 INFO - 'MONDIR': 'C:\\Monitor_config\\',
09:59:14 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
09:59:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
09:59:14 INFO - 'MOZ_NO_REMOTE': '1',
09:59:14 INFO - 'NO_EM_RESTART': '1',
09:59:14 INFO - 'NUMBER_OF_PROCESSORS': '8',
09:59:14 INFO - 'OS': 'Windows_NT',
09:59:14 INFO - 'OURDRIVE': 'C:',
09:59:14 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
09:59:14 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
09:59:14 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
09:59:14 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
09:59:14 INFO - 'PROCESSOR_LEVEL': '6',
09:59:14 INFO - 'PROCESSOR_REVISION': '1e05',
09:59:14 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
09:59:14 INFO - 'PROGRAMFILES': 'C:\\Program Files',
09:59:14 INFO - 'PROMPT': '$P$G',
09:59:14 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
09:59:14 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
09:59:14 INFO - 'PUBLIC': 'C:\\Users\\Public',
09:59:14 INFO - 'PWD': 'C:\\slave\\test',
09:59:14 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
09:59:14 INFO - 'SLAVEDIR': 'C:\\slave\\',
09:59:14 INFO - 'SYSTEMDRIVE': 'C:',
09:59:14 INFO - 'SYSTEMROOT': 'C:\\windows',
09:59:14 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:59:14 INFO - 'TEST1': 'testie',
09:59:14 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:59:14 INFO - 'USERDOMAIN': 'T-W732-IX-128',
09:59:14 INFO - 'USERNAME': 'cltbld',
09:59:14 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
09:59:14 INFO - 'WINDIR': 'C:\\windows',
09:59:14 INFO - 'WINDOWS_TRACING_FLAGS': '3',
09:59:14 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
09:59:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
09:59:15 INFO - Ignoring indexes: https://pypi.python.org/simple/
09:59:15 INFO - Downloading/unpacking jsonschema==2.5.1
09:59:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
09:59:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
09:59:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl
09:59:16 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1)
09:59:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
09:59:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
09:59:16 INFO - Downloading functools32-3.2.3-2.tar.gz
09:59:16 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32
09:59:16 INFO - warning: no files found matching '*.txt'
09:59:16 INFO - no previously-included directories found matching 'build'
09:59:16 INFO - no previously-included directories found matching 'dist'
09:59:16 INFO - no previously-included directories found matching '.git*'
09:59:16 INFO - Installing collected packages: jsonschema, functools32
09:59:16 INFO - Running setup.py install for functools32
09:59:16 INFO - warning: no files found matching '*.txt'
09:59:16 INFO - no previously-included directories found matching 'build'
09:59:16 INFO - no previously-included directories found matching 'dist'
09:59:16 INFO - no previously-included directories found matching '.git*'
09:59:16 INFO - Successfully installed jsonschema functools32
09:59:16 INFO - Cleaning up...
09:59:17 INFO - Return code: 0
09:59:17 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv
09:59:17 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')]}
09:59:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
09:59:17 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')]}
09:59:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
09:59:17 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 0x017E1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01885E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0178B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01892E90>, '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 0x012AA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01954520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-128', 'USERDOMAIN': 'T-W732-IX-128', '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-128', '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
09:59:17 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
09:59:17 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
09:59:17 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
09:59:17 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
09:59:17 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
09:59:17 INFO - 'COMPUTERNAME': 'T-W732-IX-128',
09:59:17 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
09:59:17 INFO - 'CYGWINBASE': 'C:\\cygwin',
09:59:17 INFO - 'DCLOCATION': 'SCL3',
09:59:17 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
09:59:17 INFO - 'FP_NO_HOST_CHECK': 'NO',
09:59:17 INFO - 'HOMEDRIVE': 'C:',
09:59:17 INFO - 'HOMEPATH': '\\Users\\cltbld',
09:59:17 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
09:59:17 INFO - 'KTS_VERSION': '1.19c',
09:59:17 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
09:59:17 INFO - 'LOGONSERVER': '\\\\T-W732-IX-128',
09:59:17 INFO - 'MONDIR': 'C:\\Monitor_config\\',
09:59:17 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
09:59:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
09:59:17 INFO - 'MOZ_NO_REMOTE': '1',
09:59:17 INFO - 'NO_EM_RESTART': '1',
09:59:17 INFO - 'NUMBER_OF_PROCESSORS': '8',
09:59:17 INFO - 'OS': 'Windows_NT',
09:59:17 INFO - 'OURDRIVE': 'C:',
09:59:17 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;',
09:59:17 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
09:59:17 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
09:59:17 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
09:59:17 INFO - 'PROCESSOR_LEVEL': '6',
09:59:17 INFO - 'PROCESSOR_REVISION': '1e05',
09:59:17 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
09:59:17 INFO - 'PROGRAMFILES': 'C:\\Program Files',
09:59:17 INFO - 'PROMPT': '$P$G',
09:59:17 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
09:59:17 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
09:59:17 INFO - 'PUBLIC': 'C:\\Users\\Public',
09:59:17 INFO - 'PWD': 'C:\\slave\\test',
09:59:17 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
09:59:17 INFO - 'SLAVEDIR': 'C:\\slave\\',
09:59:17 INFO - 'SYSTEMDRIVE': 'C:',
09:59:17 INFO - 'SYSTEMROOT': 'C:\\windows',
09:59:17 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:59:17 INFO - 'TEST1': 'testie',
09:59:17 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:59:17 INFO - 'USERDOMAIN': 'T-W732-IX-128',
09:59:17 INFO - 'USERNAME': 'cltbld',
09:59:17 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
09:59:17 INFO - 'WINDIR': 'C:\\windows',
09:59:17 INFO - 'WINDOWS_TRACING_FLAGS': '3',
09:59:17 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
09:59:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
09:59:17 INFO - Ignoring indexes: https://pypi.python.org/simple/
09:59:17 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages
09:59:17 INFO - Cleaning up...
09:59:17 INFO - Return code: 0
09:59:17 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv
09:59:17 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')]}
09:59:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
09:59:17 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')]}
09:59:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
09:59:17 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 0x017E1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01885E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0178B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01892E90>, '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 0x012AA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01954520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-128', 'USERDOMAIN': 'T-W732-IX-128', '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-128', '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
09:59:17 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
09:59:17 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
09:59:17 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
09:59:17 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
09:59:17 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
09:59:17 INFO - 'COMPUTERNAME': 'T-W732-IX-128',
09:59:17 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
09:59:17 INFO - 'CYGWINBASE': 'C:\\cygwin',
09:59:17 INFO - 'DCLOCATION': 'SCL3',
09:59:17 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
09:59:17 INFO - 'FP_NO_HOST_CHECK': 'NO',
09:59:17 INFO - 'HOMEDRIVE': 'C:',
09:59:17 INFO - 'HOMEPATH': '\\Users\\cltbld',
09:59:17 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
09:59:17 INFO - 'KTS_VERSION': '1.19c',
09:59:17 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
09:59:17 INFO - 'LOGONSERVER': '\\\\T-W732-IX-128',
09:59:17 INFO - 'MONDIR': 'C:\\Monitor_config\\',
09:59:17 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
09:59:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
09:59:17 INFO - 'MOZ_NO_REMOTE': '1',
09:59:17 INFO - 'NO_EM_RESTART': '1',
09:59:17 INFO - 'NUMBER_OF_PROCESSORS': '8',
09:59:17 INFO - 'OS': 'Windows_NT',
09:59:17 INFO - 'OURDRIVE': 'C:',
09:59:17 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;',
09:59:17 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
09:59:17 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
09:59:17 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
09:59:17 INFO - 'PROCESSOR_LEVEL': '6',
09:59:17 INFO - 'PROCESSOR_REVISION': '1e05',
09:59:17 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
09:59:17 INFO - 'PROGRAMFILES': 'C:\\Program Files',
09:59:17 INFO - 'PROMPT': '$P$G',
09:59:17 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
09:59:17 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
09:59:17 INFO - 'PUBLIC': 'C:\\Users\\Public',
09:59:17 INFO - 'PWD': 'C:\\slave\\test',
09:59:17 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
09:59:17 INFO - 'SLAVEDIR': 'C:\\slave\\',
09:59:17 INFO - 'SYSTEMDRIVE': 'C:',
09:59:17 INFO - 'SYSTEMROOT': 'C:\\windows',
09:59:17 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:59:17 INFO - 'TEST1': 'testie',
09:59:17 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:59:17 INFO - 'USERDOMAIN': 'T-W732-IX-128',
09:59:17 INFO - 'USERNAME': 'cltbld',
09:59:17 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
09:59:17 INFO - 'WINDIR': 'C:\\windows',
09:59:17 INFO - 'WINDOWS_TRACING_FLAGS': '3',
09:59:17 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
09:59:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
09:59:17 INFO - Ignoring indexes: https://pypi.python.org/simple/
09:59:17 INFO - Downloading/unpacking blobuploader==1.2.4
09:59:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
09:59:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
09:59:18 INFO - Downloading blobuploader-1.2.4.tar.gz
09:59:18 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader
09:59:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4)
09:59:18 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
09:59:18 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
09:59:19 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests
09:59:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4)
09:59:19 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
09:59:19 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
09:59:19 INFO - Downloading docopt-0.6.1.tar.gz
09:59:19 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt
09:59:19 INFO - Installing collected packages: blobuploader, requests, docopt
09:59:19 INFO - Running setup.py install for blobuploader
09:59:19 INFO - Running setup.py install for requests
09:59:20 INFO - Running setup.py install for docopt
09:59:20 INFO - Successfully installed blobuploader requests docopt
09:59:20 INFO - Cleaning up...
09:59:22 INFO - Return code: 0
09:59:22 INFO - Installing None into virtualenv C:\slave\test\build/venv
09:59:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
09:59:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
09:59:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
09:59:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
09:59:22 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 0x017E1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01885E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0178B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01892E90>, '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 0x012AA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01954520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-128', 'USERDOMAIN': 'T-W732-IX-128', '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-128', '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
09:59:22 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
09:59:22 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
09:59:22 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
09:59:22 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
09:59:22 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
09:59:22 INFO - 'COMPUTERNAME': 'T-W732-IX-128',
09:59:22 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
09:59:22 INFO - 'CYGWINBASE': 'C:\\cygwin',
09:59:22 INFO - 'DCLOCATION': 'SCL3',
09:59:22 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
09:59:22 INFO - 'FP_NO_HOST_CHECK': 'NO',
09:59:22 INFO - 'HOMEDRIVE': 'C:',
09:59:22 INFO - 'HOMEPATH': '\\Users\\cltbld',
09:59:22 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
09:59:22 INFO - 'KTS_VERSION': '1.19c',
09:59:22 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
09:59:22 INFO - 'LOGONSERVER': '\\\\T-W732-IX-128',
09:59:22 INFO - 'MONDIR': 'C:\\Monitor_config\\',
09:59:22 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
09:59:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
09:59:22 INFO - 'MOZ_NO_REMOTE': '1',
09:59:22 INFO - 'NO_EM_RESTART': '1',
09:59:22 INFO - 'NUMBER_OF_PROCESSORS': '8',
09:59:22 INFO - 'OS': 'Windows_NT',
09:59:22 INFO - 'OURDRIVE': 'C:',
09:59:22 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
09:59:22 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
09:59:22 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
09:59:22 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
09:59:22 INFO - 'PROCESSOR_LEVEL': '6',
09:59:22 INFO - 'PROCESSOR_REVISION': '1e05',
09:59:22 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
09:59:22 INFO - 'PROGRAMFILES': 'C:\\Program Files',
09:59:22 INFO - 'PROMPT': '$P$G',
09:59:22 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
09:59:22 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
09:59:22 INFO - 'PUBLIC': 'C:\\Users\\Public',
09:59:22 INFO - 'PWD': 'C:\\slave\\test',
09:59:22 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
09:59:22 INFO - 'SLAVEDIR': 'C:\\slave\\',
09:59:22 INFO - 'SYSTEMDRIVE': 'C:',
09:59:22 INFO - 'SYSTEMROOT': 'C:\\windows',
09:59:22 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:59:22 INFO - 'TEST1': 'testie',
09:59:22 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:59:22 INFO - 'USERDOMAIN': 'T-W732-IX-128',
09:59:22 INFO - 'USERNAME': 'cltbld',
09:59:22 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
09:59:22 INFO - 'WINDIR': 'C:\\windows',
09:59:22 INFO - 'WINDOWS_TRACING_FLAGS': '3',
09:59:22 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
09:59:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
09:59:22 INFO - Ignoring indexes: https://pypi.python.org/simple/
09:59:22 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
09:59:22 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-1wljig-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
09:59:22 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
09:59:22 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-p9bbyz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
09:59:22 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
09:59:22 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wystsk-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
09:59:22 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
09:59:22 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pxiq24-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
09:59:23 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
09:59:23 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fk2ba6-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
09:59:23 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
09:59:23 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-9l63fx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
09:59:23 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
09:59:23 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-yskimc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
09:59:23 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
09:59:23 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vuzfp7-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
09:59:23 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
09:59:23 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lghae4-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
09:59:23 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
09:59:23 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wjaghd-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
09:59:23 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
09:59:23 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-5hx7z_-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
09:59:23 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
09:59:24 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pdq2ti-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
09:59:24 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
09:59:24 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pkhgoe-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
09:59:24 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
09:59:24 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wh4k8j-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
09:59:24 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
09:59:24 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-0hmpt5-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
09:59:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
09:59:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-j29hib-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
09:59:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
09:59:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-7xl83a-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
09:59:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
09:59:25 INFO - Running setup.py install for manifestparser
09:59:25 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts
09:59:25 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts
09:59:25 INFO - Running setup.py install for mozcrash
09:59:26 INFO - Running setup.py install for mozdebug
09:59:26 INFO - Running setup.py install for mozdevice
09:59:26 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts
09:59:26 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts
09:59:26 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts
09:59:26 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts
09:59:27 INFO - Running setup.py install for mozfile
09:59:27 INFO - Running setup.py install for mozhttpd
09:59:27 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts
09:59:27 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts
09:59:28 INFO - Running setup.py install for mozinfo
09:59:28 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts
09:59:28 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts
09:59:28 INFO - Running setup.py install for mozInstall
09:59:28 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts
09:59:28 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts
09:59:28 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts
09:59:28 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts
09:59:28 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts
09:59:28 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts
09:59:28 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts
09:59:28 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts
09:59:28 INFO - Running setup.py install for mozleak
09:59:29 INFO - Running setup.py install for mozlog
09:59:29 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts
09:59:29 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts
09:59:30 INFO - Running setup.py install for moznetwork
09:59:30 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts
09:59:30 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts
09:59:30 INFO - Running setup.py install for mozprocess
09:59:31 INFO - Running setup.py install for mozprofile
09:59:31 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts
09:59:31 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts
09:59:31 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts
09:59:31 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts
09:59:31 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts
09:59:31 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts
09:59:31 INFO - Running setup.py install for mozrunner
09:59:31 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts
09:59:31 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts
09:59:32 INFO - Running setup.py install for mozscreenshot
09:59:32 INFO - Running setup.py install for moztest
09:59:33 INFO - Running setup.py install for mozversion
09:59:33 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts
09:59:33 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts
09:59:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion
09:59:33 INFO - Cleaning up...
09:59:33 INFO - Return code: 0
09:59:33 INFO - Installing None into virtualenv C:\slave\test\build/venv
09:59: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')]}
09:59:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
09:59: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')]}
09:59:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
09:59:33 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 0x017E1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01885E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0178B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01892E90>, '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 0x012AA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01954520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-128', 'USERDOMAIN': 'T-W732-IX-128', '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-128', '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
09:59:33 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
09:59:33 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
09:59:33 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
09:59:33 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
09:59:33 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
09:59:33 INFO - 'COMPUTERNAME': 'T-W732-IX-128',
09:59:33 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
09:59:33 INFO - 'CYGWINBASE': 'C:\\cygwin',
09:59:33 INFO - 'DCLOCATION': 'SCL3',
09:59:33 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
09:59:33 INFO - 'FP_NO_HOST_CHECK': 'NO',
09:59:33 INFO - 'HOMEDRIVE': 'C:',
09:59:33 INFO - 'HOMEPATH': '\\Users\\cltbld',
09:59:33 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
09:59:33 INFO - 'KTS_VERSION': '1.19c',
09:59:33 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
09:59:33 INFO - 'LOGONSERVER': '\\\\T-W732-IX-128',
09:59:33 INFO - 'MONDIR': 'C:\\Monitor_config\\',
09:59:33 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
09:59:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
09:59:33 INFO - 'MOZ_NO_REMOTE': '1',
09:59:33 INFO - 'NO_EM_RESTART': '1',
09:59:33 INFO - 'NUMBER_OF_PROCESSORS': '8',
09:59:33 INFO - 'OS': 'Windows_NT',
09:59:33 INFO - 'OURDRIVE': 'C:',
09:59: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;',
09:59:33 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
09:59:33 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
09:59:33 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
09:59:33 INFO - 'PROCESSOR_LEVEL': '6',
09:59:33 INFO - 'PROCESSOR_REVISION': '1e05',
09:59:33 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
09:59:33 INFO - 'PROGRAMFILES': 'C:\\Program Files',
09:59:33 INFO - 'PROMPT': '$P$G',
09:59:33 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
09:59:33 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
09:59:33 INFO - 'PUBLIC': 'C:\\Users\\Public',
09:59:33 INFO - 'PWD': 'C:\\slave\\test',
09:59:33 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
09:59:33 INFO - 'SLAVEDIR': 'C:\\slave\\',
09:59:33 INFO - 'SYSTEMDRIVE': 'C:',
09:59:33 INFO - 'SYSTEMROOT': 'C:\\windows',
09:59:33 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:59:33 INFO - 'TEST1': 'testie',
09:59:33 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:59:33 INFO - 'USERDOMAIN': 'T-W732-IX-128',
09:59:33 INFO - 'USERNAME': 'cltbld',
09:59:33 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
09:59:33 INFO - 'WINDIR': 'C:\\windows',
09:59:33 INFO - 'WINDOWS_TRACING_FLAGS': '3',
09:59:33 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
09:59:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
09:59:33 INFO - Ignoring indexes: https://pypi.python.org/simple/
09:59:33 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
09:59:33 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-obxdt9-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
09:59:34 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))
09:59:34 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
09:59:34 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-aw6viy-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
09:59:34 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))
09:59:34 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
09:59:34 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dtnqt7-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
09:59:34 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))
09:59:34 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
09:59:34 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lpag8f-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
09:59:34 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))
09:59:34 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
09:59:34 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gv6qp5-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
09:59:34 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))
09:59:34 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
09:59:34 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xznwpr-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
09:59:34 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))
09:59:34 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
09:59:34 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-djg8fx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
09:59:34 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))
09:59:34 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
09:59:34 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cg2lmd-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
09:59:35 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))
09:59:35 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
09:59:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-o8qtec-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
09:59:35 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))
09:59:35 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
09:59:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-e8gk5s-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
09:59:35 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))
09:59:35 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
09:59:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pgigbg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
09:59:35 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))
09:59:35 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
09:59:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ebsbjf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
09:59:35 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))
09:59:35 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
09:59:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mat5xc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
09:59:35 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))
09:59:35 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
09:59:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-x9qxs6-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
09:59:36 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))
09:59:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
09:59:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ozkwqz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
09:59:36 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))
09:59:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
09:59:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hda2lo-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
09:59:36 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))
09:59:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
09:59:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-1nuran-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
09:59:36 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))
09:59:36 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))
09:59:36 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))
09:59:36 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))
09:59:36 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))
09:59:36 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))
09:59:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10))
09:59: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
09:59: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
09:59:37 INFO - Downloading blessings-1.6.tar.gz
09:59:37 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings
09:59:38 INFO - Installing collected packages: blessings
09:59:38 INFO - Running setup.py install for blessings
09:59:38 INFO - Successfully installed blessings
09:59:38 INFO - Cleaning up...
09:59:41 INFO - Return code: 0
09:59:41 INFO - Done creating virtualenv C:\slave\test\build/venv.
09:59:41 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
09:59:41 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
09:59:41 INFO - Reading from file tmpfile_stdout
09:59:41 INFO - Using _rmtree_windows ...
09:59:41 INFO - Using _rmtree_windows ...
09:59:41 INFO - Current package versions:
09:59:41 INFO - blessings == 1.6
09:59:41 INFO - blobuploader == 1.2.4
09:59:41 INFO - docopt == 0.6.1
09:59:41 INFO - functools32 == 3.2.3-2
09:59:41 INFO - jsonschema == 2.5.1
09:59:41 INFO - manifestparser == 1.1
09:59:41 INFO - mozInstall == 1.12
09:59:41 INFO - mozcrash == 0.17
09:59:41 INFO - mozdebug == 0.1
09:59:41 INFO - mozdevice == 0.48
09:59:41 INFO - mozfile == 1.2
09:59:41 INFO - mozhttpd == 0.7
09:59:41 INFO - mozinfo == 0.9
09:59:41 INFO - mozleak == 0.1
09:59:41 INFO - mozlog == 3.4
09:59:41 INFO - moznetwork == 0.27
09:59:41 INFO - mozprocess == 0.23
09:59:41 INFO - mozprofile == 0.28
09:59:41 INFO - mozrunner == 6.12
09:59:41 INFO - mozscreenshot == 0.1
09:59:41 INFO - mozsystemmonitor == 0.3
09:59:41 INFO - moztest == 0.7
09:59:41 INFO - mozversion == 1.4
09:59:41 INFO - psutil == 3.1.1
09:59:41 INFO - requests == 1.2.3
09:59:41 INFO - Installing None into virtualenv C:\slave\test\build/venv
09:59: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')]}
09:59:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
09:59: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')]}
09:59:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
09:59:41 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 0x017E1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01885E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0178B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01892E90>, '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 0x012AA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01954520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-128', 'USERDOMAIN': 'T-W732-IX-128', '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-128', '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
09:59:41 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
09:59:41 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
09:59:41 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
09:59:41 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
09:59:41 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
09:59:41 INFO - 'COMPUTERNAME': 'T-W732-IX-128',
09:59:41 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
09:59:41 INFO - 'CYGWINBASE': 'C:\\cygwin',
09:59:41 INFO - 'DCLOCATION': 'SCL3',
09:59:41 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
09:59:41 INFO - 'FP_NO_HOST_CHECK': 'NO',
09:59:41 INFO - 'HOMEDRIVE': 'C:',
09:59:41 INFO - 'HOMEPATH': '\\Users\\cltbld',
09:59:41 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
09:59:41 INFO - 'KTS_VERSION': '1.19c',
09:59:41 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
09:59:41 INFO - 'LOGONSERVER': '\\\\T-W732-IX-128',
09:59:41 INFO - 'MONDIR': 'C:\\Monitor_config\\',
09:59:41 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
09:59:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
09:59:41 INFO - 'MOZ_NO_REMOTE': '1',
09:59:41 INFO - 'NO_EM_RESTART': '1',
09:59:41 INFO - 'NUMBER_OF_PROCESSORS': '8',
09:59:41 INFO - 'OS': 'Windows_NT',
09:59:41 INFO - 'OURDRIVE': 'C:',
09:59: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;',
09:59:41 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
09:59:41 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
09:59:41 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
09:59:41 INFO - 'PROCESSOR_LEVEL': '6',
09:59:41 INFO - 'PROCESSOR_REVISION': '1e05',
09:59:41 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
09:59:41 INFO - 'PROGRAMFILES': 'C:\\Program Files',
09:59:41 INFO - 'PROMPT': '$P$G',
09:59:41 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
09:59:41 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
09:59:41 INFO - 'PUBLIC': 'C:\\Users\\Public',
09:59:41 INFO - 'PWD': 'C:\\slave\\test',
09:59:41 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
09:59:41 INFO - 'SLAVEDIR': 'C:\\slave\\',
09:59:41 INFO - 'SYSTEMDRIVE': 'C:',
09:59:41 INFO - 'SYSTEMROOT': 'C:\\windows',
09:59:41 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:59:41 INFO - 'TEST1': 'testie',
09:59:41 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:59:41 INFO - 'USERDOMAIN': 'T-W732-IX-128',
09:59:41 INFO - 'USERNAME': 'cltbld',
09:59:41 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
09:59:41 INFO - 'WINDIR': 'C:\\windows',
09:59:41 INFO - 'WINDOWS_TRACING_FLAGS': '3',
09:59:41 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
09:59:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
09:59:41 INFO - Ignoring indexes: https://pypi.python.org/simple/
09:59:41 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))
09:59:41 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))
09:59:41 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))
09:59:41 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))
09:59:41 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))
09:59:41 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))
09:59:41 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))
09:59:41 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))
09:59:41 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))
09:59:41 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))
09:59:41 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))
09:59:41 INFO - Cleaning up...
09:59:41 INFO - Return code: 0
09:59:41 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv
09:59: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')]}
09:59:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
09:59: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')]}
09:59:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
09:59:41 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 0x017E1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01885E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0178B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01892E90>, '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 0x012AA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01954520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-128', 'USERDOMAIN': 'T-W732-IX-128', '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-128', '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
09:59:41 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
09:59:41 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
09:59:41 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
09:59:41 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
09:59:41 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
09:59:41 INFO - 'COMPUTERNAME': 'T-W732-IX-128',
09:59:41 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
09:59:41 INFO - 'CYGWINBASE': 'C:\\cygwin',
09:59:41 INFO - 'DCLOCATION': 'SCL3',
09:59:41 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
09:59:41 INFO - 'FP_NO_HOST_CHECK': 'NO',
09:59:41 INFO - 'HOMEDRIVE': 'C:',
09:59:41 INFO - 'HOMEPATH': '\\Users\\cltbld',
09:59:41 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
09:59:41 INFO - 'KTS_VERSION': '1.19c',
09:59:41 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
09:59:41 INFO - 'LOGONSERVER': '\\\\T-W732-IX-128',
09:59:41 INFO - 'MONDIR': 'C:\\Monitor_config\\',
09:59:41 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
09:59:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
09:59:41 INFO - 'MOZ_NO_REMOTE': '1',
09:59:41 INFO - 'NO_EM_RESTART': '1',
09:59:41 INFO - 'NUMBER_OF_PROCESSORS': '8',
09:59:41 INFO - 'OS': 'Windows_NT',
09:59:41 INFO - 'OURDRIVE': 'C:',
09:59: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;',
09:59:41 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
09:59:41 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
09:59:41 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
09:59:41 INFO - 'PROCESSOR_LEVEL': '6',
09:59:41 INFO - 'PROCESSOR_REVISION': '1e05',
09:59:41 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
09:59:41 INFO - 'PROGRAMFILES': 'C:\\Program Files',
09:59:41 INFO - 'PROMPT': '$P$G',
09:59:41 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
09:59:41 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
09:59:41 INFO - 'PUBLIC': 'C:\\Users\\Public',
09:59:41 INFO - 'PWD': 'C:\\slave\\test',
09:59:41 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
09:59:41 INFO - 'SLAVEDIR': 'C:\\slave\\',
09:59:41 INFO - 'SYSTEMDRIVE': 'C:',
09:59:41 INFO - 'SYSTEMROOT': 'C:\\windows',
09:59:41 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:59:41 INFO - 'TEST1': 'testie',
09:59:41 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:59:41 INFO - 'USERDOMAIN': 'T-W732-IX-128',
09:59:41 INFO - 'USERNAME': 'cltbld',
09:59:41 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
09:59:41 INFO - 'WINDIR': 'C:\\windows',
09:59:41 INFO - 'WINDOWS_TRACING_FLAGS': '3',
09:59:41 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
09:59:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
09:59:42 INFO - Ignoring indexes: https://pypi.python.org/simple/
09:59:42 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages
09:59:42 INFO - Cleaning up...
09:59:42 INFO - Return code: 0
09:59:42 INFO - Running post-action listener: _resource_record_post_action
09:59:42 INFO - Running post-action listener: _start_resource_monitoring
09:59:42 INFO - Starting resource monitoring.
09:59:42 INFO - [mozharness: 2016-09-23 16:59:42.345000Z] Finished create-virtualenv step (success)
09:59:42 INFO - [mozharness: 2016-09-23 16:59:42.345000Z] Running install step.
09:59:42 INFO - Running pre-action listener: _resource_record_pre_action
09:59:42 INFO - Running main action method: install
09:59:42 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
09:59:42 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
09:59:42 INFO - Reading from file tmpfile_stdout
09:59:42 INFO - Using _rmtree_windows ...
09:59:42 INFO - Using _rmtree_windows ...
09:59:42 INFO - Detecting whether we're running mozinstall >=1.0...
09:59:42 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h']
09:59:42 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h
09:59:42 INFO - Reading from file tmpfile_stdout
09:59:42 INFO - Output received:
09:59:42 INFO - Usage: mozinstall-script.py [options] installer
09:59:42 INFO - Options:
09:59:42 INFO - -h, --help show this help message and exit
09:59:42 INFO - -d DEST, --destination=DEST
09:59:42 INFO - Directory to install application into. [default:
09:59:42 INFO - "C:\slave\test"]
09:59:42 INFO - --app=APP Application being installed. [default: firefox]
09:59:42 INFO - Using _rmtree_windows ...
09:59:42 INFO - Using _rmtree_windows ...
09:59:42 INFO - mkdir: C:\slave\test\build\application
09:59:42 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']
09:59:42 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
09:59:45 INFO - Reading from file tmpfile_stdout
09:59:45 INFO - Output received:
09:59:45 INFO - C:\slave\test\build\application\firefox\firefox.exe
09:59:45 INFO - Using _rmtree_windows ...
09:59:45 INFO - Using _rmtree_windows ...
09:59:45 INFO - Running post-action listener: _resource_record_post_action
09:59:45 INFO - [mozharness: 2016-09-23 16:59:45.305000Z] Finished install step (success)
09:59:45 INFO - [mozharness: 2016-09-23 16:59:45.305000Z] Running run-tests step.
09:59:45 INFO - Running pre-action listener: _resource_record_pre_action
09:59:45 INFO - Running main action method: run_tests
09:59:45 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version']
09:59:45 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version
09:59:45 INFO - Python 2.7.3
09:59:45 INFO - Return code: 0
09:59:45 INFO - grabbing minidump binary from tooltool
09:59:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
09:59:45 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 0x01892E90>, '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 0x012AA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01954520>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1
09:59:45 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
09:59:45 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
09:59:45 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
09:59:45 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'...
09:59:46 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmpo9g1v6
09:59:46 INFO - INFO - File integrity verified, renaming tmpo9g1v6 to win32-minidump_stackwalk.exe
09:59:46 INFO - Return code: 0
09:59:46 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755
09:59:46 INFO - mkdir: C:\slave\test\build\blobber_upload_dir
09:59:46 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir
09:59:46 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe
09:59:46 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir
09:59:46 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WQ38qFB_S9mAMfmBMUN81Q/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-128', '--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
09:59:46 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/WQ38qFB_S9mAMfmBMUN81Q/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-128 --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
09:59:46 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
09:59:46 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
09:59:46 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
09:59:46 INFO - 'COMPUTERNAME': 'T-W732-IX-128',
09:59:46 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
09:59:46 INFO - 'CYGWINBASE': 'C:\\cygwin',
09:59:46 INFO - 'DCLOCATION': 'SCL3',
09:59:46 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
09:59:46 INFO - 'FP_NO_HOST_CHECK': 'NO',
09:59:46 INFO - 'HOMEDRIVE': 'C:',
09:59:46 INFO - 'HOMEPATH': '\\Users\\cltbld',
09:59:46 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
09:59:46 INFO - 'KTS_VERSION': '1.19c',
09:59:46 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
09:59:46 INFO - 'LOGONSERVER': '\\\\T-W732-IX-128',
09:59:46 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir',
09:59:46 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe',
09:59:46 INFO - 'MONDIR': 'C:\\Monitor_config\\',
09:59:46 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
09:59:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
09:59:46 INFO - 'MOZ_NO_REMOTE': '1',
09:59:46 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir',
09:59:46 INFO - 'NO_EM_RESTART': '1',
09:59:46 INFO - 'NUMBER_OF_PROCESSORS': '8',
09:59:46 INFO - 'OS': 'Windows_NT',
09:59:46 INFO - 'OURDRIVE': 'C:',
09:59:46 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
09:59:46 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
09:59:46 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
09:59:46 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
09:59:46 INFO - 'PROCESSOR_LEVEL': '6',
09:59:46 INFO - 'PROCESSOR_REVISION': '1e05',
09:59:46 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
09:59:46 INFO - 'PROGRAMFILES': 'C:\\Program Files',
09:59:46 INFO - 'PROMPT': '$P$G',
09:59:46 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
09:59:46 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
09:59:46 INFO - 'PUBLIC': 'C:\\Users\\Public',
09:59:46 INFO - 'PWD': 'C:\\slave\\test',
09:59:46 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos',
09:59:46 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
09:59:46 INFO - 'SLAVEDIR': 'C:\\slave\\',
09:59:46 INFO - 'SYSTEMDRIVE': 'C:',
09:59:46 INFO - 'SYSTEMROOT': 'C:\\windows',
09:59:46 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:59:46 INFO - 'TEST1': 'testie',
09:59:46 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:59:46 INFO - 'USERDOMAIN': 'T-W732-IX-128',
09:59:46 INFO - 'USERNAME': 'cltbld',
09:59:46 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
09:59:46 INFO - 'WINDIR': 'C:\\windows',
09:59:46 INFO - 'WINDOWS_TRACING_FLAGS': '3',
09:59:46 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
09:59:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
09:59:46 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WQ38qFB_S9mAMfmBMUN81Q/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-128', '--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
09:59:47 INFO - mozversion application_buildid: 20160923063008
09:59:47 INFO - mozversion application_changeset: 2e46d0c12db68401969a817832329187da8add06
09:59:47 INFO - mozversion application_display_name: Nightly
09:59:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
09:59:47 INFO - mozversion application_name: Firefox
09:59:47 INFO - mozversion application_remotingname: firefox
09:59:47 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team
09:59:47 INFO - mozversion application_vendor: Mozilla
09:59:47 INFO - mozversion application_version: 52.0a1
09:59:47 INFO - mozversion platform_buildid: 20160923063008
09:59:47 INFO - mozversion platform_changeset: 2e46d0c12db68401969a817832329187da8add06
09:59:47 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team
09:59:47 INFO - mozversion platform_version: 52.0a1
09:59:47 INFO - using testdate: 1474649987
09:59:47 INFO - actual date: 1474649987
09:59:47 INFO - starting webserver on 'localhost:49615'
09:59:47 INFO - SUITE-START | Running 2 tests
09:59:47 INFO - TEST-START | dromaeo_css
09:59:47 INFO - operating with platform_type : w7_
09:59:47 INFO - Initialising browser for dromaeo_css test...
09:59:47 INFO - TEST-INFO | started process 860 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpovwkxc\profile http://localhost:49615/getInfo.html)
09:59:54 INFO - PROCESS | 860 | __metrics Screen width/height:1600/1200
09:59:54 INFO - PROCESS | 860 | colorDepth:24
09:59:54 INFO - PROCESS | 860 | Browser inner width/height: 1010/674
09:59:54 INFO - PROCESS | 860 | __metrics
09:59:55 INFO - PROCESS | 860 | 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"
09:59:55 INFO - PROCESS | 860 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
09:59:56 INFO - TEST-INFO | 860: exit 0
09:59:56 INFO - Browser initialized.
09:59:56 INFO - Running cycle 1/1 for dromaeo_css test...
09:59:56 INFO - TEST-INFO | started process 1980 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpovwkxc\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1)
09:59:56 INFO - PROCESS | 1980 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0
10:00:07 INFO - PROCESS | 1980 | RSS: Main: 124317696
10:00:07 INFO - PROCESS | 1980 |
10:00:59 INFO - PROCESS | 1980 | 1474650059554 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]
10:03:09 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49615/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49615/tests/dromaeo/cssquery-ext.html)
10:03:10 INFO - PROCESS | 1980 | RSS: Main: 208457728
10:03:10 INFO - PROCESS | 1980 |
10:06:12 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49615/tests/dromaeo/cssquery-ext.html (next: http://localhost:49615/tests/dromaeo/cssquery-jquery.html)
10:06:13 INFO - PROCESS | 1980 | RSS: Main: 198447104
10:06:13 INFO - PROCESS | 1980 |
10:09:15 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49615/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49615/tests/dromaeo/cssquery-mootools.html)
10:09:16 INFO - PROCESS | 1980 | RSS: Main: 144707584
10:09:16 INFO - PROCESS | 1980 |
10:12:18 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49615/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49615/tests/dromaeo/cssquery-prototype.html)
10:12:18 INFO - PROCESS | 1980 | RSS: Main: 133378048
10:12:18 INFO - PROCESS | 1980 |
10:15:21 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49615/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49615/tests/dromaeo/cssquery-yui.html)
10:15:22 INFO - PROCESS | 1980 | RSS: Main: 133062656
10:15:22 INFO - PROCESS | 1980 |
10:18:24 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49615/tests/dromaeo/cssquery-yui.html (next: http://localhost:49615/tests/dromaeo/cssquery-dojo.html)
10:18:24 INFO - PROCESS | 1980 | RSS: Main: 184946688
10:18:24 INFO - PROCESS | 1980 |
10:18:24 INFO - PROCESS | 1980 | __start_tp_report
10:18:24 INFO - PROCESS | 1980 | _x_x_mozilla_page_load
10:18:24 INFO - PROCESS | 1980 | _x_x_mozilla_page_load_details
10:18:24 INFO - PROCESS | 1980 | |i|pagename|runs|
10:18:24 INFO - PROCESS | 1980 | |0;dojo.html;2692.6230327754115;2710.4850078485088;2755.5728862026367;2768.155712507685;2773.1680495851238;4664.836730714425;4706.929396059062;4742.810287588497;4811.3264143019915;4823.397075365575;4640.67515273931;4709.764511774406;4712.387389639351;4770;4897.971426000544;4000.059985903325;4136;4174.561671024537;4217.557156498554;4223.535411104776;4023.5574086850424;4192.2663533881605;4200.894977625553;4216.7680777557225;4238.660907127422;4181.832726690929;4187.916241675165;4240.703150779446;4251.702380833343;4273.786310684469;4150.27370210212;4197.349410841324;4232.217039847638;4322.848700295506;4324.891877703051;4111.218868414991;4175.227582897143;4177.49870015597;4185.560516145792;4231.196072746168;4684.742339171345;4703.2474804031535;4722.315264286659;4731.408573928259;4784.736839473828;4818.831340903052;4966.453690094087;5023.497650234984;5053.69677819332;5060.924086138711;4711.458182309044;4911.557959783637;4919;5016.473270306638;5100.515451032146;4623.260278355447;4630.606398456135;4648.697834640738;4654.069186162781;4657.208274593328;5083.491650834961;5085.211792172219;5087.364079490064;5088.084144853888;5092.796288148433;4844.878878028077;4879.26810978349;4893.070316639827;4896.2410826321975;4896.926546101811;4643.698159619614;4909.877253068702;4950.603951683856;4981.12830254704;4990.351254336913;4910.165271903785;4970;4982.60139188864;5004.174311238605;5008.5993120550265;4948.134076536592;4962.60299176065;5018.774155163026;5031.195008798648;5036.445990940993;3252.3170134271595;3285.5564498792355;3373.730101591867;3391.796492210475;3392.9651456306065;3028.6214223222096;3041.8935337263533;3063.892763753258;3119.906402807919;3128.812271263686;4424.292113261863;4553.521880202531;4560.4071470708595;4766.308885211625;4789.8323558675975;4861.95138048615;5022.547970682656;5126.256692779526;5127.051495473349;5142.305788718475;4632.2125238709505;4755.421909050542;4809.591184749335;4858.611311095104;4868.82959096437;3951.269015232191;4069.2471892699946;4141.440905477722;4157.937630935538;4180.28935081037;5183.637145399785;5232.607554433433;5288.338957630296;5344.973275133677;5345.919811202913;4917.409910810726;4971.825986090469;4988.176950803077;5082.695038297714;5085.974570127126;5090.567301779242;5218.765155567932;5275.788968441219;5283.524482796567;5290.65610735301;4744.667873248839;4953.504649535018;5034.521720436553;5035.564864013739;5041.722705251246;4932.161532539405;4957.529034741694;5023.8492845214705;5099.3370861787735;5148.613853960893;4867.026594681007;4870.586000190024;4906.1659517881335;4911.3369695090705;5011.173156429294;4709.670323077352;4793.784279707352;4846.612271018381;4881.585065269492;4920.384951881015;4695.849516868389;4758.714477131383;4822.204336284473;4853.927191092066;4859.198232291632;4791.185498465339;4829.7585120744525;4896.485868983706;4960;4969.105560999054;4872.90254194907;4875.780589873535;4881.755912204447;4883.633727470383;4884.4382895966
10:18:24 INFO - PROCESS | 1980 | |1;ext.html;507.2619338861955;511.13617985604327;511.6520765879202;529.7033661149759;540.4622400711293;27849.443011139763;37957.62042379576;38638.613613863854;39186.60813391865;39381.80309098458;39109.41335879964;39216.21567568647;39294;39802.800985995105;39847.20305593894;6643.601383916956;8094.271515563583;8138.023437187516;8245.587720613945;8283.751487455385;6574.934250657504;6814.6933387997415;6821.113255276807;9710.854337184912;9919.3056486046;10004.54979525923;10166.339187952797;10171.338862973884;10183.541740621653;10191.643292484765;10017.198624110053;10195.674562306853;10197.286189966742;10204.74488137795;10222.437765922872;6489.059086432488;6644.667766611698;9573.425594464285;9748.463834489101;9900.059494348025;34858.825705871306;35532.28935421277;36870;36878.44682329767;37078;2783.6102945587636;2891.3783536539536;2953.18787333485;3215.389076075538;3477.8434970426392;28072;29481.852590737122;29702.257443563867;30022.249443763863;30477.08568742941;3228.725558327545;3299.290652509722;3311.8344082796007;3375.088726043979;3391.6099648540485;296125;296371.5181424101;301183;311084;315033;49572.25641615379;50531.74734126306;50834;50885.491145088075;51063.74468127636;51364.74317628388;52834;53267.73366133145;53286.46713532892;53292.733536332846;11009.119270458345;11501.309921404743;11575.363355015555;11597.536098555964;11629.53481860733;8550.444221125732;8735.43219690718;8736.825263494822;8769.429987050822;8803;13486.865131348757;14146.646333841736;15491;15708.607284817743;16087.839121608635;6372.936270637327;6382.553221274559;6400.327965563642;6465.77369792055;6481.157449531531;747.2079595628651;792.84935862186;807.2784463622695;821.4250024982537;922.9893266872764;26788.464230715665;27057.594136087973;27303.726962730514;27557.44885102327;27561.862190688917;874.9737507874773;881.4800158290437;887.5650931043871;902.5848109869403;926.4395041000232;13962.650933726534;14310.785338219936;14330.713385732435;14374.137551746928;14536.41854325815;449260;450080;462510;469321.5339233025;469908;46849.76575117103;48241;48649.51350486521;48744.51255487471;48755.75622121937;969.0019280141465;973.2019743810149;1080.7946490166844;1105;1106.562907651473;26176.214713558624;26636.46727065409;27563.586546202223;28271.717282827733;28349;42351;42632.57367426286;42761.786191068844;42882.78558607187;42915.785421072695;382992;383313;384198;605669;629213;35431.46852797262;36518.45222321614;37666;37779;38011.61988380191;40394.59605403908;40841.255035821356;41166.79416602898;42210.15579688451;42517;35148.29703405969;35151.47272790961;35515.75694850668;36734.081647959014;37673.43489847709;35272;35805;36204;37010.259794803416;37484.43773343456;34045;34055.489167663;34057.65942340545;34129.65870341265;34150.82924585361;30850;31293.374132517678;32037.35925281528;32204.67795322017;33011.504827427125;24351.51296974086;24356.26931192067;24621.38446538851;24889.377765556004;25164
10:18:24 INFO - PROCESS | 1980 | |2;jquery.html;272.19159097480474;287.27606431791884;294.1719060843725;295.7855554722826;300.3047944009618;12946.158499697513;17217.741733873987;17240.655186896256;17363.871348362347;17680.823191768093;16104.75842862358;16330.611897988656;16481.587960301;16799.16004199791;17037.977721336727;5177.119889618773;5337.546308563767;5481.451854814526;5554.333479982388;5555.583331250173;4922.778474968635;5087.592992560586;5103.183490641498;5168.819091331804;5332.413434522199;5067.5185857343495;5146.922796158061;5151.459096794839;5152;5153.742312884359;5005.374328208974;5105.361829771278;5153.742312884378;5154.252633368159;5156.613254005946;4682.508336624639;4838.250071238964;4989.226669866177;4992.850214493535;4998.425181104183;9413.435193888388;10057.195424366033;10091.495425228783;10199.18406527476;10326.225533084973;1828.05854984683;1837.6600328939055;1964.3910387779833;1985.4936991067186;2254.4251215939994;7390.9652648629235;7462.7014919403155;7465.402767778564;7476.102867655862;7709.95915551399;1621.2947367895003;1687.3841048017507;1705.1644694099803;1728.7493313469477;1804.0979510244877;75001;82987;85055;85102.57448712779;90747.09252907452;7871.409644276702;8284.751457456287;8343.579132555624;8357.12250213731;8581.27059199975;9133.360664753536;9302.674406395743;9460.148586627236;9804.607815687292;9805.950970245103;4406.713563618355;4733.976330118397;4823.782929768169;6219.253689557282;6642.4021838034805;6075.513958883367;6161.106639537242;6284.654344011124;6309.369063093745;6406.661568527547;3303.8513266903046;3395;3422.845971931269;3447.3622379859808;3600.037979301287;1383.858316888551;1411.371939486939;1418.2058047493438;1422.587449639593;1452.847451017649;761.7866997240782;778.9298963093349;803.9397045221632;826.3719573124469;867.492516877621;17179.57051073708;17544.385946492068;17634.623055444703;18279.54301142482;18413.81586184121;741.6291854072964;752.6989204318317;778.2373274191324;794.162158922331;807.3662175192446;9704.320697551244;9834.75413114678;9879.604815807288;9932.255080868963;10218.795624087477;381913;385305;388115;390005;396918;70422.08457689066;87797;88937.55531222302;91751;97227.51386243023;809.0938149272735;813.7314686153555;853.7097386888488;872.908344623818;879.9428037177563;16260.430884918971;16309.59226019359;16785.832141678427;16918;17118.229679664197;44338;44663;44788.552114478436;45812;47044.7647761759;58486.7075664619;59016;62585;63685.68157159184;65129.67435162794;6619.172603424572;6795.38841504267;6957.721691132298;7054.435645148274;7065.858682826418;8297.792555185928;12426.254424734545;12488.875111248772;13231;13332.600021999355;10520.263581549218;10588.841167382341;11051.392173430217;11169.385683787144;11294.774104517699;9633.56648950813;9968.601255949681;10069.647562335284;10234.692959211236;10955.50700218508;10223.284370094021;10415.791684166426;10644.148468122377;10684.519196636014;11225.887741122484;6639;6656;6678.899816502659;6705.329467053256;6853.725850966105;6526;9848.818141823027;9884.752881178028;10274.22943279272;10458.26792124544
10:18:24 INFO - PROCESS | 1980 | |3;mootools.html;998.5656239535807;1121.2823792772626;1121.4673030310603;1217.2331431198343;1288.4974859804672;5912.526997840173;6666.833329166773;6752;6882.346177113172;7094.822629434273;6673.432758215546;6792.558483698556;7165.928340716591;7175.5694658320535;7182.569045857253;1779.7419374190736;1819.981800182001;1856.6565185440736;1872.6067525819594;1879.3234435603172;1772.1936518883942;1785.1788177438393;1832.193834712722;1858.526075850663;1910.560571068655;1829.6798060339388;1918.5683221275242;1922.6346994071152;1926.2295081967254;1929.0740444586536;1742.041876967669;1742.5904912345588;1751.8160593137663;1785.9819902655493;1787.276153157973;1737.2529812180758;1758.2088060372755;1792.4443422539055;1823.917923693437;1843;6733.360330768569;6823.1812182538415;6836.145481814773;6844.691988860513;6872.244053154149;4139.565345638694;4268.509121451011;4285.935710964338;4291.334843099325;4308.590683885026;2620;2760.9723902760916;2793.2597861566496;2833.5466325388047;2842.204182828811;837.2213841127749;869.1699427047218;879.6569337958201;900.7793090692822;902.8871391076116;8978.191962723386;9083.909160908439;9141.725748227564;9228;9274.907250927472;3463.4631632097066;3537.20412907099;3652.1052342176336;3717.4609681596557;3745.5879853216125;7957.283844454027;7971.362291016705;8035.156308587631;8039.799005024921;8047.839043219103;4188.916221675549;4278.935815962763;4313.97843010783;4333;4341.60925516705;3443.3113377324635;3532.9401179646;3590.964090359063;3867.303885300673;4010.9197816044098;2766.4467106578763;2932.9120126395815;3000.9946667866075;3113.8131712097347;3277.6886195811358;1016.7508960304625;1018.2312354172552;1035.5288343803552;1035.5961175141701;1038.5638032026568;750.3284560318484;773.1572585881416;776.3944123583614;782.6282515805061;792.4571668407159;4595.414581969215;4642.605378542862;4679.882679255213;4741.193997020515;4946.950530494721;715.5706576054325;734.6033142103205;760.6878135216707;780.8321210939675;787.763670898728;3133.6552979172275;3208.1017315151794;3226.0321903428876;3357.026462325898;3371.4437117875277;7843.843123137505;8103.432759706882;8208.261256486945;8445.915540844635;8646.740597782076;8319.875201871977;8592.699255526151;8631.223189912938;8639.913600864036;8645.95677021611;6313.842153946096;6571.474282057424;6688.699008544627;6783.660816959133;6791.72833086671;4655.464621568428;4887.560119589254;4928.457869634266;4944.1100601892;4982.825601103944;7663.88504172449;7927.881081783663;8047;8134.14591467911;8196.262336389753;7438.735414979575;7794.337481313925;7819.257170568787;8294.253517183482;8320.254316075801;5790.536757059509;5799.130130480293;6119.938800611937;6213.844653883689;6216.906746398898;5919.4968427684125;6440.710168042356;6536.281009089092;6934.445244380337;6952.374286314256;5744.741486633028;5798.507126894261;5849.210356601804;5882.058870580687;6055.909161362495;5845.853853653693;5865.4134586541;6305.8108256752375;6306.9684651576445;6311.147995020797;5704.971475142598;5759.596828221985;5806.216160818235;5814.883702326015;5826.300843898759;3951.2887680217837;4192.182524407711;4198.517170525429;4262.360645903139;4332.241857674831;4145.875623731293;4225.28170211071;4297.978510107429;4366.628836548929;4374.015846434527
10:18:24 INFO - PROCESS | 1980 | |4;prototype.html;230.83149301010272;241.60497586446158;245.34859946841132;245.75670086614247;247.00088143459692;4983.850484485462;4988.678000329911;5051.621128415365;5091.770870310836;5204.167333226685;4637.791299391527;4858.295547145671;4929.260610908365;4981.857801145506;4994.875128121799;3397.4903764435285;3493.6331685173072;3544.5746510418658;3590.563774490205;3662.212624285778;3699.926001479969;3701.222743223926;3706.147586055209;3706.6664000240107;3718.3492888744495;3473.419594084698;3587.874424395147;3668.394714872043;3676.3933950898067;3689.2252626948375;3554.875579354723;3561.7506774525796;3576.5529308836267;3657.9451308230396;3660.762050466738;3106.456370135217;3117.37652469507;3122.8282444465544;3130.843457827122;3139.7959132656306;4695.624350051988;4713.835015774465;4955.950440495585;4966.826161084381;4989.600831933437;4014.6788256939376;4027.65764909983;4028.892054684956;4032.576579459144;4172.061286210609;4995.350604421438;5003.949960500385;5028.371453568304;5283.920741188886;5294.126469132589;3171.413288541627;3203.2792621660174;3304.966950330514;3404.9659503404896;3412.4540073588355;5266.578673706094;5269.762860671279;5278.47215278473;5324.307839980816;5384.488473595002;3273.8035717857006;3276.737860971117;3278.4426647469986;3326.6340702522702;3343.866245350159;3316.93366132676;3322.850471728778;3338.332333533303;3516.4197907345;3523.788572685647;4088.936876412154;4091.4272001919753;4206.032612499082;4231.597998190167;4232.830686772495;5129.358830146231;5332.30680011596;5360.142377219626;5374.328208973879;5393.217983392465;1120.8598925134359;1138.8064029114905;1141.2695874640237;1157.548556063136;1164.6971787335185;683.4737252315689;695.7634404302562;699.3950233048377;708.0936401406208;724.5000949345041;4780.976095119572;4899.363082799285;4997.675151115166;5244.370675518962;5261.184516399964;5236.476352364733;5553.4724201200825;5567.053600887859;5572.0527510323345;5586.245856809279;4974.353334066548;5000.524950129732;5065.772040258197;5216.878371150145;5225;2769.3907340385085;2782.9304267393477;2807.5648274517075;2817.60553522507;2906.520424130037;5588.497035266845;5593.860153496195;5604.551635869121;5605.495505404451;5699.601027928005;5342.54588359986;5543.7782488700805;5567.47109024642;5659.717014149276;5660.773569057192;5377.569794416438;5582.302212223472;5632.324121105412;5873.353931067579;5886.8822623547285;5258.395284542326;5265.710385928811;5281.419043905091;5283.89432211346;5284.9735751320995;5180.870478238075;5291.4443983382735;5348.171033489815;5489.58828087887;5490.890182196414;5681.147827825859;5830.591858569859;5896.970515147397;5907.9704601476715;5914.34942156371;3327.966720332766;3481.686648201674;3499.9125021873638;3500.0899766060497;3504.001359612498;5332.360116786011;5343.198520221998;5529.336479622471;5579.46995035471;5599.776008959596;5156.458571849902;5172.405173404956;5391.838244852661;5401.243825864461;5423.3491980962535;5233.953209358067;5280.86797830042;5398.433164517669;5412.539934105645;5429.972850135724;5031.84904452867;5044.571211447068;5050.621203409685;5282.234076059027;5288.65623734456;5242.16125419931;5310.495502927216;5386.973065134649;5431.044823863485;5458.553483326842;4903.092927808367;4986.451490335988;5228.686278823283;5371.194320851903;5373.704446255337
10:18:24 INFO - PROCESS | 1980 | |5;yui.html;592.7303077099917;603.6559161278074;613.0252897892352;626.5332327416075;658.9308122647122;13075.476980920763;13864.237466939314;14000.649983750385;14015.369308381134;14032.859671403283;11907.285562866235;11955.880441195608;12319.63041108768;12618.242905425657;12740.681482962931;571.0976656882137;598.9191459153002;601.5999360425312;601.9668918209496;602.5751844949322;485.8323878261994;493.7259820799455;578.7684926029601;581.9447152520504;599.0235915457798;626.7023163997088;627.5669787846394;629.1852051593198;630.3035146163492;630.486153784664;619.9473044791175;627.8179327994876;629.2040568680623;629.3203340392388;630.8265227062594;369.774437593068;375.82899780599763;443.36820031455045;447.38261199544746;458.6170547592764;11292.153088518395;11492.942535287353;11677.70805729855;11684.70788230301;11692.941535292353;12147.453364598614;12346.197497162655;12578.748425031448;12689.936550317281;12777.552785652551;11535.769284614345;11582.131340149523;11602.361870097142;11741.354225517593;12030.473600543897;3976.880693579197;3991.900202494961;4083.1833633273463;4100.425940368351;4133.359329303963;80010.59994700048;80816.19183808204;82117;82336;82544.17455825425;8963;9231.399959002643;9341.346105772667;9393.671221507351;9430.19843313326;17138.657226855394;17373.7393939091;17477.563060923578;17789.555261118574;17895.194716237802;6858.142732158481;6861.897071543931;7076.681549330293;7164;7413.147488038837;3629.5100161477862;3696.77819330841;3753.0617345663586;3761.6238376162164;3833.2716783811543;2677.236987458565;2715.4704832557463;2825.5479123340165;2830.6037154798346;2837.3757773289844;480.118981668635;495.3659316075429;499.8500449865026;508.52706982505885;526.4261954469647;508.3061620887462;575.4849409778225;618.8947878860605;637.5823835387829;653.0530730440785;7911.683532658629;8359.790980460213;8839.51382673959;8852.424592401601;8912.8663070054;618.9597676151036;622.4988883948465;627.1690010735794;628.6793735194992;634.8254230086764;4253.59590838876;4259.2759230930815;4281.207976524353;4364.519902810688;4395.780210989437;105371.4731426338;108562.45718771356;109925;111565.44217278862;113140;67984;87811;98384;98473;99347.50326248322;68866;85635.5718221405;85744.57127714322;86270.56864715637;88958.55520722354;8065.233802788725;8288.295494883003;8352.498850069016;8452.492850428995;8513.148685131437;43058;44060.779696101315;44713.776431117636;45247.321290180014;45358;45290;45409.09181816279;55765.721171395504;63250;64051.35948640454;9691.321607487409;9834.655787047419;11390.601328953448;11536.192466527598;11973.10201734856;12204.267743935392;12977;13961.441542338192;14006.78989815174;14035.92982035124;12253.326067066397;12418.875811241773;12433;12510.687232819253;12652.936735316265;10785.946070269598;12036.819447708467;12073.27560346382;12292.201006934878;12995.740085198055;12289.446974885972;12329.691757706129;12355.25868447896;12486.250824950532;12551.246925184152;9836.458994755358;10265.794684106128;10506.159507239556;11282.435878205893;11428.771424571629;10414.062734353945;10503.684889453329;10592.258541901992;10652.414117223627;10784.622538211124
10:18:24 INFO - PROCESS | 1980 | __end_tp_report
10:18:24 INFO - PROCESS | 1980 | __start_cc_report
10:18:24 INFO - PROCESS | 1980 | _x_x_mozilla_cycle_collect,946
10:18:24 INFO - PROCESS | 1980 | __end_cc_report
10:18:24 INFO - PROCESS | 1980 | __startTimestamp1474651104885__endTimestamp
10:18:24 INFO - PROCESS | 1980 |
10:18:24 INFO - PROCESS | 1980 | ------- Summary: start -------
10:18:24 INFO - PROCESS | 1980 | Number of tests: 6
10:18:24 INFO - PROCESS | 1980 |
10:18:24 INFO - PROCESS | 1980 | [#0] dojo.html Cycles:165 Average:4610.13 Median:4829.76 stddev:594.36 (12.3%) stddev-sans-first:576.83
10:18:24 INFO - PROCESS | 1980 | Values: 2692.6 2710.5 2755.6 2768.2 2773.2 4664.8 4706.9 4742.8 4811.3 4823.4 4640.7 4709.8 4712.4 4770.0 4898.0 4000.1 4136.0 4174.6 4217.6 4223.5 4023.6 4192.3 4200.9 4216.8 4238.7 4181.8 4187.9 4240.7 4251.7 4273.8 4150.3 4197.3 4232.2 4322.8 4324.9 4111.2 4175.2 4177.5 4185.6 4231.2 4684.7 4703.2 4722.3 4731.4 4784.7 4818.8 4966.5 5023.5 5053.7 5060.9 4711.5 4911.6 4919.0 5016.5 5100.5 4623.3 4630.6 4648.7 4654.1 4657.2 5083.5 5085.2 5087.4 5088.1 5092.8 4844.9 4879.3 4893.1 4896.2 4896.9 4643.7 4909.9 4950.6 4981.1 4990.4 4910.2 4970.0 4982.6 5004.2 5008.6 4948.1 4962.6 5018.8 5031.2 5036.4 3252.3 3285.6 3373.7 3391.8 3393.0 3028.6 3041.9 3063.9 3119.9 3128.8 4424.3 4553.5 4560.4 4766.3 4789.8 4862.0 5022.5 5126.3 5127.1 5142.3 4632.2 4755.4 4809.6 4858.6 4868.8 3951.3 4069.2 4141.4 4157.9 4180.3 5183.6 5232.6 5288.3 5345.0 5345.9 4917.4 4971.8 4988.2 5082.7 5086.0 5090.6 5218.8 5275.8 5283.5 5290.7 4744.7 4953.5 5034.5 5035.6 5041.7 4932.2 4957.5 5023.8 5099.3 5148.6 4867.0 4870.6 4906.2 4911.3 5011.2 4709.7 4793.8 4846.6 4881.6 4920.4 4695.8 4758.7 4822.2 4853.9 4859.2 4791.2 4829.8 4896.5 4960.0 4969.1 4872.9 4875.8 4881.8 4883.6 4884.4
10:18:24 INFO - PROCESS | 1980 |
10:18:24 INFO - PROCESS | 1980 | [#1] ext.html Cycles:180 Average:55106.58 Median:37669.72 stddev:113540.53 (301.4%) stddev-sans-first:113785.04
10:18:24 INFO - PROCESS | 1980 | Values: 507.3 511.1 511.7 529.7 540.5 27849.4 37957.6 38638.6 39186.6 39381.8 39109.4 39216.2 39294.0 39802.8 39847.2 6643.6 8094.3 8138.0 8245.6 8283.8 6574.9 6814.7 6821.1 9710.9 9919.3 10004.5 10166.3 10171.3 10183.5 10191.6 10017.2 10195.7 10197.3 10204.7 10222.4 6489.1 6644.7 9573.4 9748.5 9900.1 34858.8 35532.3 36870.0 36878.4 37078.0 2783.6 2891.4 2953.2 3215.4 3477.8 28072.0 29481.9 29702.3 30022.2 30477.1 3228.7 3299.3 3311.8 3375.1 3391.6 296125.0 296371.5 301183.0 311084.0 315033.0 49572.3 50531.7 50834.0 50885.5 51063.7 51364.7 52834.0 53267.7 53286.5 53292.7 11009.1 11501.3 11575.4 11597.5 11629.5 8550.4 8735.4 8736.8 8769.4 8803.0 13486.9 14146.6 15491.0 15708.6 16087.8 6372.9 6382.6 6400.3 6465.8 6481.2 747.2 792.8 807.3 821.4 923.0 26788.5 27057.6 27303.7 27557.4 27561.9 875.0 881.5 887.6 902.6 926.4 13962.7 14310.8 14330.7 14374.1 14536.4 449260.0 450080.0 462510.0 469321.5 469908.0 46849.8 48241.0 48649.5 48744.5 48755.8 969.0 973.2 1080.8 1105.0 1106.6 26176.2 26636.5 27563.6 28271.7 28349.0 42351.0 42632.6 42761.8 42882.8 42915.8 382992.0 383313.0 384198.0 605669.0 629213.0 35431.5 36518.5 37666.0 37779.0 38011.6 40394.6 40841.3 41166.8 42210.2 42517.0 35148.3 35151.5 35515.8 36734.1 37673.4 35272.0 35805.0 36204.0 37010.3 37484.4 34045.0 34055.5 34057.7 34129.7 34150.8 30850.0 31293.4 32037.4 32204.7 33011.5 24351.5 24356.3 24621.4 24889.4 25164.0
10:18:24 INFO - PROCESS | 1980 |
10:18:24 INFO - PROCESS | 1980 | [#2] jquery.html Cycles:180 Average:24849.23 Median:5095.39 stddev:65106.83 (1277.8%) stddev-sans-first:65263.32
10:18:24 INFO - PROCESS | 1980 | Values: 272.2 287.3 294.2 295.8 300.3 12946.2 17217.7 17240.7 17363.9 17680.8 16104.8 16330.6 16481.6 16799.2 17038.0 5177.1 5337.5 5481.5 5554.3 5555.6 4922.8 5087.6 5103.2 5168.8 5332.4 5067.5 5146.9 5151.5 5152.0 5153.7 5005.4 5105.4 5153.7 5154.3 5156.6 4682.5 4838.3 4989.2 4992.9 4998.4 9413.4 10057.2 10091.5 10199.2 10326.2 1828.1 1837.7 1964.4 1985.5 2254.4 7391.0 7462.7 7465.4 7476.1 7710.0 1621.3 1687.4 1705.2 1728.7 1804.1 75001.0 82987.0 85055.0 85102.6 90747.1 7871.4 8284.8 8343.6 8357.1 8581.3 9133.4 9302.7 9460.1 9804.6 9806.0 4406.7 4734.0 4823.8 6219.3 6642.4 6075.5 6161.1 6284.7 6309.4 6406.7 3303.9 3395.0 3422.8 3447.4 3600.0 1383.9 1411.4 1418.2 1422.6 1452.8 761.8 778.9 803.9 826.4 867.5 17179.6 17544.4 17634.6 18279.5 18413.8 741.6 752.7 778.2 794.2 807.4 9704.3 9834.8 9879.6 9932.3 10218.8 381913.0 385305.0 388115.0 390005.0 396918.0 70422.1 87797.0 88937.6 91751.0 97227.5 809.1 813.7 853.7 872.9 879.9 16260.4 16309.6 16785.8 16918.0 17118.2 44338.0 44663.0 44788.6 45812.0 47044.8 58486.7 59016.0 62585.0 63685.7 65129.7 6619.2 6795.4 6957.7 7054.4 7065.9 8297.8 12426.3 12488.9 13231.0 13332.6 10520.3 10588.8 11051.4 11169.4 11294.8 9633.6 9968.6 10069.6 10234.7 10955.5 10223.3 10415.8 10644.1 10684.5 11225.9 6639.0 6656.0 6678.9 6705.3 6853.7 6526.0 9848.8 9884.8 10274.2 10458.3
10:18:24 INFO - PROCESS | 1980 |
10:18:24 INFO - PROCESS | 1980 | [#3] mootools.html Cycles:180 Average:4557.36 Median:5752.17 stddev:2542.66 (44.2%) stddev-sans-first:2535.72
10:18:24 INFO - PROCESS | 1980 | Values: 998.6 1121.3 1121.5 1217.2 1288.5 5912.5 6666.8 6752.0 6882.3 7094.8 6673.4 6792.6 7165.9 7175.6 7182.6 1779.7 1820.0 1856.7 1872.6 1879.3 1772.2 1785.2 1832.2 1858.5 1910.6 1829.7 1918.6 1922.6 1926.2 1929.1 1742.0 1742.6 1751.8 1786.0 1787.3 1737.3 1758.2 1792.4 1823.9 1843.0 6733.4 6823.2 6836.1 6844.7 6872.2 4139.6 4268.5 4285.9 4291.3 4308.6 2620.0 2761.0 2793.3 2833.5 2842.2 837.2 869.2 879.7 900.8 902.9 8978.2 9083.9 9141.7 9228.0 9274.9 3463.5 3537.2 3652.1 3717.5 3745.6 7957.3 7971.4 8035.2 8039.8 8047.8 4188.9 4278.9 4314.0 4333.0 4341.6 3443.3 3532.9 3591.0 3867.3 4010.9 2766.4 2932.9 3001.0 3113.8 3277.7 1016.8 1018.2 1035.5 1035.6 1038.6 750.3 773.2 776.4 782.6 792.5 4595.4 4642.6 4679.9 4741.2 4947.0 715.6 734.6 760.7 780.8 787.8 3133.7 3208.1 3226.0 3357.0 3371.4 7843.8 8103.4 8208.3 8445.9 8646.7 8319.9 8592.7 8631.2 8639.9 8646.0 6313.8 6571.5 6688.7 6783.7 6791.7 4655.5 4887.6 4928.5 4944.1 4982.8 7663.9 7927.9 8047.0 8134.1 8196.3 7438.7 7794.3 7819.3 8294.3 8320.3 5790.5 5799.1 6119.9 6213.8 6216.9 5919.5 6440.7 6536.3 6934.4 6952.4 5744.7 5798.5 5849.2 5882.1 6055.9 5845.9 5865.4 6305.8 6307.0 6311.1 5705.0 5759.6 5806.2 5814.9 5826.3 3951.3 4192.2 4198.5 4262.4 4332.2 4145.9 4225.3 4298.0 4366.6 4374.0
10:18:24 INFO - PROCESS | 1980 |
10:18:24 INFO - PROCESS | 1980 | [#4] prototype.html Cycles:180 Average:4319.86 Median:5016.16 stddev:1409.34 (28.1%) stddev-sans-first:1379.47
10:18:24 INFO - PROCESS | 1980 | Values: 230.8 241.6 245.3 245.8 247.0 4983.9 4988.7 5051.6 5091.8 5204.2 4637.8 4858.3 4929.3 4981.9 4994.9 3397.5 3493.6 3544.6 3590.6 3662.2 3699.9 3701.2 3706.1 3706.7 3718.3 3473.4 3587.9 3668.4 3676.4 3689.2 3554.9 3561.8 3576.6 3657.9 3660.8 3106.5 3117.4 3122.8 3130.8 3139.8 4695.6 4713.8 4956.0 4966.8 4989.6 4014.7 4027.7 4028.9 4032.6 4172.1 4995.4 5003.9 5028.4 5283.9 5294.1 3171.4 3203.3 3305.0 3405.0 3412.5 5266.6 5269.8 5278.5 5324.3 5384.5 3273.8 3276.7 3278.4 3326.6 3343.9 3316.9 3322.9 3338.3 3516.4 3523.8 4088.9 4091.4 4206.0 4231.6 4232.8 5129.4 5332.3 5360.1 5374.3 5393.2 1120.9 1138.8 1141.3 1157.5 1164.7 683.5 695.8 699.4 708.1 724.5 4781.0 4899.4 4997.7 5244.4 5261.2 5236.5 5553.5 5567.1 5572.1 5586.2 4974.4 5000.5 5065.8 5216.9 5225.0 2769.4 2782.9 2807.6 2817.6 2906.5 5588.5 5593.9 5604.6 5605.5 5699.6 5342.5 5543.8 5567.5 5659.7 5660.8 5377.6 5582.3 5632.3 5873.4 5886.9 5258.4 5265.7 5281.4 5283.9 5285.0 5180.9 5291.4 5348.2 5489.6 5490.9 5681.1 5830.6 5897.0 5908.0 5914.3 3328.0 3481.7 3499.9 3500.1 3504.0 5332.4 5343.2 5529.3 5579.5 5599.8 5156.5 5172.4 5391.8 5401.2 5423.3 5234.0 5280.9 5398.4 5412.5 5430.0 5031.8 5044.6 5050.6 5282.2 5288.7 5242.2 5310.5 5387.0 5431.0 5458.6 4903.1 4986.5 5228.7 5371.2 5373.7
10:18:24 INFO - PROCESS | 1980 |
10:18:24 INFO - PROCESS | 1980 | [#5] yui.html Cycles:180 Average:18866.62 Median:22252.07 stddev:28263.66 (127.0%) stddev-sans-first:28309.64
10:18:24 INFO - PROCESS | 1980 | Values: 592.7 603.7 613.0 626.5 658.9 13075.5 13864.2 14000.6 14015.4 14032.9 11907.3 11955.9 12319.6 12618.2 12740.7 571.1 598.9 601.6 602.0 602.6 485.8 493.7 578.8 581.9 599.0 626.7 627.6 629.2 630.3 630.5 619.9 627.8 629.2 629.3 630.8 369.8 375.8 443.4 447.4 458.6 11292.2 11492.9 11677.7 11684.7 11692.9 12147.5 12346.2 12578.7 12689.9 12777.6 11535.8 11582.1 11602.4 11741.4 12030.5 3976.9 3991.9 4083.2 4100.4 4133.4 80010.6 80816.2 82117.0 82336.0 82544.2 8963.0 9231.4 9341.3 9393.7 9430.2 17138.7 17373.7 17477.6 17789.6 17895.2 6858.1 6861.9 7076.7 7164.0 7413.1 3629.5 3696.8 3753.1 3761.6 3833.3 2677.2 2715.5 2825.5 2830.6 2837.4 480.1 495.4 499.9 508.5 526.4 508.3 575.5 618.9 637.6 653.1 7911.7 8359.8 8839.5 8852.4 8912.9 619.0 622.5 627.2 628.7 634.8 4253.6 4259.3 4281.2 4364.5 4395.8 105371.5 108562.5 109925.0 111565.4 113140.0 67984.0 87811.0 98384.0 98473.0 99347.5 68866.0 85635.6 85744.6 86270.6 88958.6 8065.2 8288.3 8352.5 8452.5 8513.1 43058.0 44060.8 44713.8 45247.3 45358.0 45290.0 45409.1 55765.7 63250.0 64051.4 9691.3 9834.7 11390.6 11536.2 11973.1 12204.3 12977.0 13961.4 14006.8 14035.9 12253.3 12418.9 12433.0 12510.7 12652.9 10785.9 12036.8 12073.3 12292.2 12995.7 12289.4 12329.7 12355.3 12486.3 12551.2 9836.5 10265.8 10506.2 11282.4 11428.8 10414.1 10503.7 10592.3 10652.4 10784.6
10:18:24 INFO - PROCESS | 1980 | -------- Summary: end --------
10:18:24 INFO - PROCESS | 1980 |
10:18:25 INFO - TEST-INFO | 1980: exit 0
10:18:26 INFO - TEST-OK | dromaeo_css | took 1118867ms
10:18:26 INFO - TEST-START | kraken
10:18:26 INFO - operating with platform_type : w7_
10:18:26 INFO - Initialising browser for kraken test...
10:18:26 INFO - TEST-INFO | started process 4060 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpwyeg44\profile http://localhost:49615/getInfo.html)
10:18:30 INFO - PROCESS | 4060 | __metrics Screen width/height:1600/1200
10:18:30 INFO - PROCESS | 4060 | colorDepth:24
10:18:30 INFO - PROCESS | 4060 | Browser inner width/height: 1010/674
10:18:30 INFO - PROCESS | 4060 | __metrics
10:18:31 INFO - PROCESS | 4060 | 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"
10:18:31 INFO - PROCESS | 4060 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
10:18:31 INFO - PROCESS | 4060 | console.error:
10:18:31 INFO - PROCESS | 4060 | Message: Error: Connection closed before committing the transaction.
10:18:31 INFO - PROCESS | 4060 | Stack:
10:18:31 INFO - PROCESS | 4060 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:937:23
10:18:31 INFO - PROCESS | 4060 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7
10:18:31 INFO - PROCESS | 4060 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11
10:18:31 INFO - PROCESS | 4060 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7
10:18:31 INFO - PROCESS | 4060 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7
10:18:31 INFO - PROCESS | 4060 | TaskImpl_run@resource://gre/modules/Task.jsm:324:13
10:18:31 INFO - PROCESS | 4060 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9
10:18:31 INFO - PROCESS | 4060 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15
10:18:31 INFO - PROCESS | 4060 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5
10:18:31 INFO - PROCESS | 4060 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29
10:18:31 INFO - PROCESS | 4060 | Barrier/this.client.addBlocker/promise13.2%
10:19:29 INFO - TinderboxPrint: I/O read bytes / time
206,999,552 / 2,241,700
10:19:29 INFO - TinderboxPrint: I/O write bytes / time
469,854,720 / 3,323,710
10:19:29 INFO - TinderboxPrint: CPU idle
8,237.9 (86.8%)
10:19:29 INFO - TinderboxPrint: CPU user
1,210.9 (12.8%)
10:19:29 INFO - install - Wall time: 3s; CPU: 9.0%; Read bytes: 0; Write bytes: 35851776; Read time: 0; Write time: 350400
10:19:29 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 206999552; Write bytes: 396995584; Read time: 2241700; Write time: 2948750
10:19:29 INFO - Running post-run listener: _upload_blobber_files
10:19:29 INFO - Blob upload gear active.
10:19:29 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir.
10:19:29 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
10:19:29 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\venv\\Scripts\\blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', 'C:\\slave\\test\\oauth.txt', '-b', 'Fx-Team', '-d', 'C:\\slave\\test\\build\\blobber_upload_dir', '--output-manifest', 'C:\\slave\\test\\build\\uploaded_files.json']
10:19:29 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\venv\Scripts\blobberc.py -u https://blobupload.elasticbeanstalk.com -a C:\slave\test\oauth.txt -b Fx-Team -d C:\slave\test\build\blobber_upload_dir --output-manifest C:\slave\test\build\uploaded_files.json
10:19:30 INFO - (blobuploader) - INFO - Open directory for files ...
10:19:30 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log ...
10:19:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
10:19:30 INFO - (blobuploader) - INFO - Uploading, attempt #1.
10:19:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded
10:19:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
10:19:31 INFO - (blobuploader) - INFO - Done attempting.
10:19:31 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log ...
10:19:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
10:19:31 INFO - (blobuploader) - INFO - Uploading, attempt #1.
10:19:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded
10:19:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
10:19:31 INFO - (blobuploader) - INFO - Done attempting.
10:19:31 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ...
10:19:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
10:19:31 INFO - (blobuploader) - INFO - Uploading, attempt #1.
10:19:39 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
10:19:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
10:19:39 INFO - (blobuploader) - INFO - Done attempting.
10:19:39 INFO - (blobuploader) - INFO - Iteration through files over.
10:19:39 INFO - Return code: 0
10:19:39 INFO - rmtree: C:\slave\test\build\uploaded_files.json
10:19:39 INFO - Using _rmtree_windows ...
10:19:39 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1
10:19:39 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/983858cfa48101edf8a255dc4be3b013d3260b4782267b59ebf4352756e9a3cf32af49fa144cb8c7bf232b0a17420ef93548bf221874ee87496cd25c0f1c334b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d8bf7767012433e17467039ce7708d856762b44a35d4fc5dfa43188ec2aed20f8aa2e8a5213f9e13f9f8566c255773ff7fcfc7e6c5dfdacf09280b4c29c3a98b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9f4ce31bd095546404b393aa7c1db58af10d6c03fed4c2a28a497e5d5b9c5a4ea754f490e5e87ea386d8fed750e7bac3e26acd8854692a7e1882c9d970d3714c"}
10:19:39 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files
10:19:39 INFO - Writing to file C:\slave\test\properties\blobber_files
10:19:39 INFO - Contents:
10:19:39 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/983858cfa48101edf8a255dc4be3b013d3260b4782267b59ebf4352756e9a3cf32af49fa144cb8c7bf232b0a17420ef93548bf221874ee87496cd25c0f1c334b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d8bf7767012433e17467039ce7708d856762b44a35d4fc5dfa43188ec2aed20f8aa2e8a5213f9e13f9f8566c255773ff7fcfc7e6c5dfdacf09280b4c29c3a98b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9f4ce31bd095546404b393aa7c1db58af10d6c03fed4c2a28a497e5d5b9c5a4ea754f490e5e87ea386d8fed750e7bac3e26acd8854692a7e1882c9d970d3714c"}
10:19:39 INFO - Running post-run listener: copy_logs_to_upload_dir
10:19:39 INFO - Copying logs to upload dir...
10:19:39 INFO - mkdir: C:\slave\test\build\upload\logs
10:19:39 INFO - Copying logs to upload dir...
10:19:39 INFO - Using _rmtree_windows ...
10:19:39 INFO - Using _rmtree_windows ...
10:19:39 INFO - Using _rmtree_windows ...
10:19:39 INFO - Using _rmtree_windows ...
10:19:39 INFO - Using _rmtree_windows ...
10:19:39 INFO - Using _rmtree_windows ...
10:19:39 INFO - Using _rmtree_windows ...
program finished with exit code 0
elapsedTime=1420.249000
========= master_lag: 1.40 =========
========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 41 secs) (at 2016-09-23 10:19:41.205990) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-23 10:19:41.216265) =========
'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-128
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-128
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-128
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/983858cfa48101edf8a255dc4be3b013d3260b4782267b59ebf4352756e9a3cf32af49fa144cb8c7bf232b0a17420ef93548bf221874ee87496cd25c0f1c334b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d8bf7767012433e17467039ce7708d856762b44a35d4fc5dfa43188ec2aed20f8aa2e8a5213f9e13f9f8566c255773ff7fcfc7e6c5dfdacf09280b4c29c3a98b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9f4ce31bd095546404b393aa7c1db58af10d6c03fed4c2a28a497e5d5b9c5a4ea754f490e5e87ea386d8fed750e7bac3e26acd8854692a7e1882c9d970d3714c"}
build_url:https://queue.taskcluster.net/v1/task/WQ38qFB_S9mAMfmBMUN81Q/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/WQ38qFB_S9mAMfmBMUN81Q/artifacts/public/build/firefox-52.0a1.en-US.win32.zip'
blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/983858cfa48101edf8a255dc4be3b013d3260b4782267b59ebf4352756e9a3cf32af49fa144cb8c7bf232b0a17420ef93548bf221874ee87496cd25c0f1c334b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d8bf7767012433e17467039ce7708d856762b44a35d4fc5dfa43188ec2aed20f8aa2e8a5213f9e13f9f8566c255773ff7fcfc7e6c5dfdacf09280b4c29c3a98b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9f4ce31bd095546404b393aa7c1db58af10d6c03fed4c2a28a497e5d5b9c5a4ea754f490e5e87ea386d8fed750e7bac3e26acd8854692a7e1882c9d970d3714c"}'
========= master_lag: 0.08 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-23 10:19:41.396648) =========
========= Started 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2016-09-23 10:19:41.396982) =========
'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-128
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-128
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-128
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: 1.03 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2016-09-23 10:19:42.529092) =========
========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-09-23 10:19:42.529410) =========
========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-09-23 10:19:43.234449) =========
========= Total master_lag: 11.21 =========