builder: autoland_win7_ix_test-dromaeojs
slave: t-w732-ix-142
starttime: 1475078983.52
results: success (0)
buildid: 20160928070622
builduid: 815ec2276f404c1b99dd131e46846e2c
revision: bf025a49293e21d5a46786bc05e4713f0ba4cc0f
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-28 09:09:43.518752) =========
master: http://buildbot-master126.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-28 09:09:43.519221) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-28 09:09:43.519649) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-28 09:09:43.675983) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-28 09:09:43.676387) =========
'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-142
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-142
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-142
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:09:44-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
=> `archiver_client.py'
Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102
Connecting to hg.mozilla.org|63.245.215.25|:443... connected.
WARNING: Certificate verification error for hg.mozilla.org: certificate signature failure
HTTP request sent, awaiting response... 200 Script output follows
Length: 12,141 (12K) [text/x-python]
0K .......... . 100% 8.93 MB/s
09:09:44 (8.93 MB/s) - `archiver_client.py' saved [12141/12141]
program finished with exit code 0
elapsedTime=1.177000
========= master_lag: 0.03 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-28 09:09:44.882582) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 16 secs) (at 2016-09-28 09:09:44.883115) =========
'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-142
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-142
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-142
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=16.601000
========= master_lag: 0.11 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 16 secs) (at 2016-09-28 09:10:01.590571) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-28 09:10:01.591004) =========
'bash' '-c' u'python archiver_client.py mozharness --repo integration/autoland --rev bf025a49293e21d5a46786bc05e4713f0ba4cc0f --destination scripts --debug'
in dir C:\slave\test\. (timeout 1200 secs)
watching logfiles {}
argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev bf025a49293e21d5a46786bc05e4713f0ba4cc0f --destination scripts --debug']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-142
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-142
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-142
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
2016-09-28 09:10:01,970 truncating revision to first 12 chars
2016-09-28 09:10:01,970 Setting DEBUG logging.
2016-09-28 09:10:01,970 attempt 1/10
2016-09-28 09:10:01,970 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/bf025a49293e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2016-09-28 09:10:02,480 unpacking tar archive at: autoland-bf025a49293e/testing/mozharness/
2016-09-28 09:10:02,619 skipping symlink on windows: configs/single_locale/linux32.py
program finished with exit code 0
elapsedTime=1.227000
========= master_lag: 0.25 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-28 09:10:03.066507) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-28 09:10:03.067096) =========
script_repo_revision: bf025a49293e21d5a46786bc05e4713f0ba4cc0f
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-28 09:10:03.067802) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-28 09:10:03.068280) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-28 09:10:03.079999) =========
========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 22 mins, 37 secs) (at 2016-09-28 09:10:03.080552) =========
'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Autoland-Non-PGO' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Autoland-Non-PGO'
in dir C:\slave\test\. (timeout 3600 secs) (maxTime 7200 secs)
watching logfiles {}
argv: ['c:/mozilla-build/python27/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland-Non-PGO', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland-Non-PGO']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-142
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-142
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-142
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:10:03 INFO - MultiFileLogger online at 20160928 09:10:03 in C:\slave\test
09:10:03 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO
09:10:03 INFO - Dumping config to C:\slave\test\logs\localconfig.json.
09:10:03 INFO - {'append_to_log': False,
09:10:03 INFO - 'base_work_dir': 'C:\\slave\\test',
09:10:03 INFO - 'blob_upload_branch': 'Autoland-Non-PGO',
09:10:03 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt',
09:10:03 INFO - 'branch': 'Autoland-Non-PGO',
09:10:03 INFO - 'buildbot_json_path': 'buildprops.json',
09:10:03 INFO - 'config_files': ('talos/windows_config.py',),
09:10:03 INFO - 'default_actions': ('clobber',
09:10:03 INFO - 'read-buildbot-config',
09:10:03 INFO - 'download-and-extract',
09:10:03 INFO - 'populate-webroot',
09:10:03 INFO - 'create-virtualenv',
09:10:03 INFO - 'install',
09:10:03 INFO - 'run-tests'),
09:10:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
09:10:03 INFO - 'download_minidump_stackwalk': True,
09:10:03 INFO - 'download_symbols': 'ondemand',
09:10:03 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python',
09:10:03 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'),
09:10:03 INFO - 'hg': 'c:/mozilla-build/hg/hg',
09:10:03 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python',
09:10:03 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'),
09:10:03 INFO - 'python': 'c:/mozilla-build/python27/python.exe',
09:10:03 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe',
09:10:03 INFO - 'C:/mozilla-build/tooltool.py'),
09:10:03 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe',
09:10:03 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')},
09:10:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
09:10:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
09:10:03 INFO - 'installer_path': 'installer.exe',
09:10:03 INFO - 'log_level': 'info',
09:10:03 INFO - 'log_name': 'talos',
09:10:03 INFO - 'log_to_console': True,
09:10:03 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe',
09:10:03 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe',
09:10:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest',
09:10:03 INFO - 'opt_config_files': (),
09:10:03 INFO - 'pip_index': False,
09:10:03 INFO - 'sps_profile': False,
09:10:03 INFO - 'sps_profile_interval': 0,
09:10:03 INFO - 'suite': 'dromaeojs',
09:10:03 INFO - 'system_bits': '32',
09:10:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
09:10:03 INFO - 'title': 'T-W732-IX-142',
09:10:03 INFO - 'use_talos_json': True,
09:10:03 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'),
09:10:03 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv',
09:10:03 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll',
09:10:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
09:10:03 INFO - 'work_dir': 'build'}
09:10:03 INFO - [mozharness: 2016-09-28 16:10:03.704000Z] Running clobber step.
09:10:03 INFO - Running pre-action listener: _resource_record_pre_action
09:10:03 INFO - Running main action method: clobber
09:10:03 INFO - rmtree: C:\slave\test\build
09:10:03 INFO - Using _rmtree_windows ...
09:10:03 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1
09:10:23 INFO - Running post-action listener: _resource_record_post_action
09:10:23 INFO - [mozharness: 2016-09-28 16:10:23.627000Z] Finished clobber step (success)
09:10:23 INFO - [mozharness: 2016-09-28 16:10:23.627000Z] Running read-buildbot-config step.
09:10:23 INFO - Running pre-action listener: _resource_record_pre_action
09:10:23 INFO - Running main action method: read_buildbot_config
09:10:23 INFO - Using buildbot properties:
09:10:23 INFO - {
09:10:23 INFO - "project": "",
09:10:23 INFO - "product": "firefox",
09:10:23 INFO - "script_repo_revision": "production",
09:10:23 INFO - "builddir": "autoland_win7_ix_test-dromaeojs",
09:10:23 INFO - "repository": "",
09:10:23 INFO - "buildername": "Windows 7 32-bit autoland talos dromaeojs",
09:10:23 INFO - "buildid": "20160928070622",
09:10:23 INFO - "pgo_build": "False",
09:10:23 INFO - "basedir": "C:\\slave\\test",
09:10:23 INFO - "buildnumber": 227,
09:10:23 INFO - "slavename": "t-w732-ix-142",
09:10:23 INFO - "master": "http://buildbot-master126.bb.releng.scl3.mozilla.com:8201/",
09:10:23 INFO - "platform": "win7_ix",
09:10:23 INFO - "scheduler": "tests-autoland-win32-talos",
09:10:23 INFO - "branch": "autoland",
09:10:23 INFO - "repo_path": "integration/autoland",
09:10:23 INFO - "revision": "bf025a49293e21d5a46786bc05e4713f0ba4cc0f",
09:10:23 INFO - "stage_platform": "win32",
09:10:23 INFO - "builduid": "815ec2276f404c1b99dd131e46846e2c",
09:10:23 INFO - "slavebuilddir": "test"
09:10:23 INFO - }
09:10:23 INFO - Found installer url https://queue.taskcluster.net/v1/task/fDJXzSd1Qeik6j77PXtRRg/artifacts/public/build/firefox-52.0a1.en-US.win32.zip.
09:10:23 INFO - Running post-action listener: _resource_record_post_action
09:10:23 INFO - [mozharness: 2016-09-28 16:10:23.630000Z] Finished read-buildbot-config step (success)
09:10:23 INFO - [mozharness: 2016-09-28 16:10:23.631000Z] Running download-and-extract step.
09:10:23 INFO - Running pre-action listener: _resource_record_pre_action
09:10:23 INFO - Running main action method: download_and_extract
09:10:23 INFO - mkdir: C:\slave\test\build\tests
09:10:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
09:10:23 INFO - trying https://queue.taskcluster.net/v1/task/fDJXzSd1Qeik6j77PXtRRg/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json
09:10:23 INFO - Downloading https://queue.taskcluster.net/v1/task/fDJXzSd1Qeik6j77PXtRRg/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:10:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fDJXzSd1Qeik6j77PXtRRg/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:10:24 INFO - Downloaded 1274 bytes.
09:10:24 INFO - Reading from file C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json
09:10:24 INFO - Using the following test package requirements:
09:10:24 INFO - {u'common': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
09:10:24 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
09:10:24 INFO - u'firefox-52.0a1.en-US.win32.cppunittest.tests.zip'],
09:10:24 INFO - u'gtest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
09:10:24 INFO - u'firefox-52.0a1.en-US.win32.gtest.tests.zip'],
09:10:24 INFO - u'jittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
09:10:24 INFO - u'jsshell-win32.zip'],
09:10:24 INFO - u'mochitest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
09:10:24 INFO - u'firefox-52.0a1.en-US.win32.mochitest.tests.zip'],
09:10:24 INFO - u'mozbase': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
09:10:24 INFO - u'reftest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
09:10:24 INFO - u'firefox-52.0a1.en-US.win32.reftest.tests.zip'],
09:10:24 INFO - u'talos': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
09:10:24 INFO - u'firefox-52.0a1.en-US.win32.talos.tests.zip'],
09:10:24 INFO - u'web-platform': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
09:10:24 INFO - u'firefox-52.0a1.en-US.win32.web-platform.tests.zip'],
09:10:24 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
09:10:24 INFO - u'firefox-52.0a1.en-US.win32.xpcshell.tests.zip']}
09:10:24 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip'] for test suite category: common
09:10:24 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/fDJXzSd1Qeik6j77PXtRRg/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip
09:10:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fDJXzSd1Qeik6j77PXtRRg/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1
09:10:24 INFO - Fetch https://queue.taskcluster.net/v1/task/fDJXzSd1Qeik6j77PXtRRg/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory
09:10:25 INFO - Expected file size: 17322609
09:10:25 INFO - Content-Encoding None
09:10:25 INFO - Content-Type application/zip
09:10:25 INFO - Http code 200
09:11:01 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:11:01 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/fDJXzSd1Qeik6j77PXtRRg/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip
09:11:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fDJXzSd1Qeik6j77PXtRRg/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1
09:11:01 INFO - Fetch https://queue.taskcluster.net/v1/task/fDJXzSd1Qeik6j77PXtRRg/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory
09:11:02 INFO - Expected file size: 17322609
09:11:02 INFO - Content-Encoding None
09:11:02 INFO - Content-Type application/zip
09:11:02 INFO - Http code 200
09:11:12 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/fDJXzSd1Qeik6j77PXtRRg/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip
09:11:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fDJXzSd1Qeik6j77PXtRRg/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip'}, attempt #1
09:11:12 INFO - Fetch https://queue.taskcluster.net/v1/task/fDJXzSd1Qeik6j77PXtRRg/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip into memory
09:11:12 INFO - Expected file size: 14414057
09:11:12 INFO - Content-Encoding None
09:11:12 INFO - Content-Type application/zip
09:11:12 INFO - Http code 200
09:11:28 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:11:28 INFO - trying https://queue.taskcluster.net/v1/task/fDJXzSd1Qeik6j77PXtRRg/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
09:11:28 INFO - Downloading https://queue.taskcluster.net/v1/task/fDJXzSd1Qeik6j77PXtRRg/artifacts/public/build/firefox-52.0a1.en-US.win32.zip to C:\slave\test\build\firefox-52.0a1.en-US.win32.zip
09:11:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fDJXzSd1Qeik6j77PXtRRg/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:11:30 INFO - Downloaded 53528184 bytes.
09:11:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fDJXzSd1Qeik6j77PXtRRg/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
09:11:30 INFO - mkdir: C:\slave\test\properties
09:11:30 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url
09:11:30 INFO - Writing to file C:\slave\test\properties\build_url
09:11:30 INFO - Contents:
09:11:30 INFO - build_url:https://queue.taskcluster.net/v1/task/fDJXzSd1Qeik6j77PXtRRg/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
09:11:31 INFO - Running post-action listener: _resource_record_post_action
09:11:31 INFO - Running post-action listener: set_extra_try_arguments
09:11:31 INFO - [mozharness: 2016-09-28 16:11:31.122000Z] Finished download-and-extract step (success)
09:11:31 INFO - [mozharness: 2016-09-28 16:11:31.122000Z] Running populate-webroot step.
09:11:31 INFO - Running pre-action listener: _resource_record_pre_action
09:11:31 INFO - Running main action method: populate_webroot
09:11:31 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']},
09:11:31 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']},
09:11:31 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome',
09:11:31 INFO - u'--tppagecycles',
09:11:31 INFO - u'7'],
09:11:31 INFO - u'tests': [u'tsvgm']}},
09:11:31 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'],
09:11:31 INFO - u'tests': [u'tresize', u'tcanvasmark']},
09:11:31 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']},
09:11:31 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'],
09:11:31 INFO - u'tests': [u'dromaeo_css', u'kraken']},
09:11:31 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']},
09:11:31 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
09:11:31 INFO - u'talos_options': [u'--disable-e10s'],
09:11:31 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
09:11:31 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
09:11:31 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
09:11:31 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
09:11:31 INFO - u'talos_options': [u'--disable-e10s'],
09:11:31 INFO - u'tests': [u'damp', u'tps']},
09:11:31 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
09:11:31 INFO - u'tests': [u'damp', u'tps']},
09:11:31 INFO - u'g3': {u'talos_options': [u'--disable-e10s'],
09:11:31 INFO - u'tests': [u'dromaeo_dom']},
09:11:31 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']},
09:11:31 INFO - u'g4': {u'talos_options': [u'--disable-e10s'],
09:11:31 INFO - u'tests': [u'basic_compositor_video']},
09:11:31 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']},
09:11:31 INFO - u'other': {u'talos_options': [u'--disable-e10s'],
09:11:31 INFO - u'tests': [u'a11yr',
09:11:31 INFO - u'ts_paint',
09:11:31 INFO - u'tpaint',
09:11:31 INFO - u'sessionrestore',
09:11:31 INFO - u'sessionrestore_no_auto_restore',
09:11:31 INFO - u'tabpaint']},
09:11:31 INFO - u'other-e10s': {u'tests': [u'a11yr',
09:11:31 INFO - u'ts_paint',
09:11:31 INFO - u'tpaint',
09:11:31 INFO - u'sessionrestore',
09:11:31 INFO - u'sessionrestore_no_auto_restore',
09:11:31 INFO - u'tabpaint']},
09:11:31 INFO - u'other-e10s_l64': {u'tests': [u'a11yr',
09:11:31 INFO - u'ts_paint',
09:11:31 INFO - u'tpaint',
09:11:31 INFO - u'sessionrestore',
09:11:31 INFO - u'sessionrestore_no_auto_restore',
09:11:31 INFO - u'tabpaint']},
09:11:31 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr',
09:11:31 INFO - u'ts_paint',
09:11:31 INFO - u'tpaint',
09:11:31 INFO - u'sessionrestore',
09:11:31 INFO - u'sessionrestore_no_auto_restore',
09:11:31 INFO - u'tabpaint']},
09:11:31 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'],
09:11:31 INFO - u'tests': [u'a11yr',
09:11:31 INFO - u'ts_paint',
09:11:31 INFO - u'tpaint',
09:11:31 INFO - u'sessionrestore',
09:11:31 INFO - u'sessionrestore_no_auto_restore',
09:11:31 INFO - u'tabpaint']},
09:11:31 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'],
09:11:31 INFO - u'tests': [u'a11yr',
09:11:31 INFO - u'ts_paint',
09:11:31 INFO - u'tpaint',
09:11:31 INFO - u'sessionrestore',
09:11:31 INFO - u'sessionrestore_no_auto_restore',
09:11:31 INFO - u'tabpaint']},
09:11:31 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'],
09:11:31 INFO - u'tests': [u'tsvgx',
09:11:31 INFO - u'tsvgr_opacity',
09:11:31 INFO - u'tart',
09:11:31 INFO - u'tscrollx',
09:11:31 INFO - u'cart']},
09:11:31 INFO - u'svgr-e10s': {u'tests': [u'tsvgx',
09:11:31 INFO - u'tsvgr_opacity',
09:11:31 INFO - u'tart',
09:11:31 INFO - u'tscrollx',
09:11:31 INFO - u'cart']},
09:11:31 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
09:11:31 INFO - u'talos_options': [u'--disable-e10s'],
09:11:31 INFO - u'tests': [u'tp5o']},
09:11:31 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
09:11:31 INFO - u'tests': [u'tp5o']},
09:11:31 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
09:11:31 INFO - u'talos_options': [u'--disable-e10s',
09:11:31 INFO - u'--xperf_path',
09:11:31 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
09:11:31 INFO - u'tests': [u'tp5n']},
09:11:31 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
09:11:31 INFO - u'talos_options': [u'--xperf_path',
09:11:31 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
09:11:31 INFO - u'tests': [u'tp5n']}},
09:11:31 INFO - u'talos.zip': {u'path': u'',
09:11:31 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}}
09:11:31 INFO - Running post-action listener: _resource_record_post_action
09:11:31 INFO - [mozharness: 2016-09-28 16:11:31.195000Z] Finished populate-webroot step (success)
09:11:31 INFO - [mozharness: 2016-09-28 16:11:31.195000Z] Running create-virtualenv step.
09:11:31 INFO - Running pre-action listener: _resource_record_pre_action
09:11:31 INFO - Running main action method: create_virtualenv
09:11:31 INFO - Creating virtualenv C:\slave\test\build/venv
09:11:31 INFO - mkdir: C:\slave\test\build\venv\Scripts
09:11:34 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll
09:11:34 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:11:34 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:11:34 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:11:42 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe
09:11:48 INFO - Installing distribute......................................................................................................................................................................................done.
09:11:50 INFO - Return code: 0
09:11:50 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version']
09:11:50 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version
09:11:51 INFO - Reading from file tmpfile_stdout
09:11:51 INFO - Output received:
09:11:51 INFO - pip 0.8.2 from c:\slave\test\build\venv\lib\site-packages\pip-0.8.2-py2.7.egg (python 2.7)
09:11:51 INFO - Using _rmtree_windows ...
09:11:51 INFO - Using _rmtree_windows ...
09:11:51 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv
09:11:51 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:11:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
09:11:51 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:11:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
09:11:51 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 0x018A0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01947E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0184B228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01954E90>, '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 0x0136A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F5E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-142', 'USERDOMAIN': 'T-W732-IX-142', '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-142', '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:11:51 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:11:51 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:11:51 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
09:11:51 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
09:11:51 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
09:11:51 INFO - 'COMPUTERNAME': 'T-W732-IX-142',
09:11:51 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
09:11:51 INFO - 'CYGWINBASE': 'C:\\cygwin',
09:11:51 INFO - 'DCLOCATION': 'SCL3',
09:11:51 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
09:11:51 INFO - 'FP_NO_HOST_CHECK': 'NO',
09:11:51 INFO - 'HOMEDRIVE': 'C:',
09:11:51 INFO - 'HOMEPATH': '\\Users\\cltbld',
09:11:51 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
09:11:51 INFO - 'KTS_VERSION': '1.19c',
09:11:51 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
09:11:51 INFO - 'LOGONSERVER': '\\\\T-W732-IX-142',
09:11:51 INFO - 'MONDIR': 'C:\\Monitor_config\\',
09:11:51 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
09:11:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
09:11:51 INFO - 'MOZ_NO_REMOTE': '1',
09:11:51 INFO - 'NO_EM_RESTART': '1',
09:11:51 INFO - 'NUMBER_OF_PROCESSORS': '8',
09:11:51 INFO - 'OS': 'Windows_NT',
09:11:51 INFO - 'OURDRIVE': 'C:',
09:11:51 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:11:51 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
09:11:51 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
09:11:51 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
09:11:51 INFO - 'PROCESSOR_LEVEL': '6',
09:11:51 INFO - 'PROCESSOR_REVISION': '1e05',
09:11:51 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
09:11:51 INFO - 'PROGRAMFILES': 'C:\\Program Files',
09:11:51 INFO - 'PROMPT': '$P$G',
09:11:51 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
09:11:51 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
09:11:51 INFO - 'PUBLIC': 'C:\\Users\\Public',
09:11:51 INFO - 'PWD': 'C:\\slave\\test',
09:11:51 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
09:11:51 INFO - 'SLAVEDIR': 'C:\\slave\\',
09:11:51 INFO - 'SYSTEMDRIVE': 'C:',
09:11:51 INFO - 'SYSTEMROOT': 'C:\\windows',
09:11:51 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:11:51 INFO - 'TEST1': 'testie',
09:11:51 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:11:51 INFO - 'USERDOMAIN': 'T-W732-IX-142',
09:11:51 INFO - 'USERNAME': 'cltbld',
09:11:51 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
09:11:51 INFO - 'WINDIR': 'C:\\windows',
09:11:51 INFO - 'WINDOWS_TRACING_FLAGS': '3',
09:11:51 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
09:11:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
09:11:52 INFO - Ignoring indexes: http://pypi.python.org/simple/
09:11:52 INFO - Downloading/unpacking pip>=1.5
09:11:56 INFO - Running setup.py egg_info for package pip
09:11:56 INFO - warning: no files found matching 'pip\cacert.pem'
09:11:56 INFO - warning: no files found matching '*.html' under directory 'docs'
09:11:56 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
09:11:56 INFO - no previously-included directories found matching 'docs\_build\_sources'
09:11:56 INFO - Installing collected packages: pip
09:11:56 INFO - Found existing installation: pip 0.8.2
09:11:56 INFO - Uninstalling pip:
09:11:56 INFO - Successfully uninstalled pip
09:11:56 INFO - Running setup.py install for pip
09:11:56 INFO - warning: no files found matching 'pip\cacert.pem'
09:11:56 INFO - warning: no files found matching '*.html' under directory 'docs'
09:11:56 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
09:11:56 INFO - no previously-included directories found matching 'docs\_build\_sources'
09:11:56 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts
09:11:56 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts
09:11:56 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts
09:11:56 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts
09:11:56 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts
09:11:56 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts
09:11:56 INFO - Successfully installed pip
09:11:56 INFO - Cleaning up...
09:11:56 INFO - Return code: 0
09:11:56 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv
09:11:56 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:11:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
09:11:56 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:11:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
09:11:56 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 0x018A0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01947E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0184B228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01954E90>, '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 0x0136A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F5E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-142', 'USERDOMAIN': 'T-W732-IX-142', '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-142', '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:11:56 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:11:56 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:11:56 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
09:11:56 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
09:11:56 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
09:11:56 INFO - 'COMPUTERNAME': 'T-W732-IX-142',
09:11:56 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
09:11:56 INFO - 'CYGWINBASE': 'C:\\cygwin',
09:11:56 INFO - 'DCLOCATION': 'SCL3',
09:11:56 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
09:11:56 INFO - 'FP_NO_HOST_CHECK': 'NO',
09:11:56 INFO - 'HOMEDRIVE': 'C:',
09:11:56 INFO - 'HOMEPATH': '\\Users\\cltbld',
09:11:56 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
09:11:56 INFO - 'KTS_VERSION': '1.19c',
09:11:56 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
09:11:56 INFO - 'LOGONSERVER': '\\\\T-W732-IX-142',
09:11:56 INFO - 'MONDIR': 'C:\\Monitor_config\\',
09:11:56 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
09:11:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
09:11:56 INFO - 'MOZ_NO_REMOTE': '1',
09:11:56 INFO - 'NO_EM_RESTART': '1',
09:11:56 INFO - 'NUMBER_OF_PROCESSORS': '8',
09:11:56 INFO - 'OS': 'Windows_NT',
09:11:56 INFO - 'OURDRIVE': 'C:',
09:11:56 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:11:56 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
09:11:56 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
09:11:56 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
09:11:56 INFO - 'PROCESSOR_LEVEL': '6',
09:11:56 INFO - 'PROCESSOR_REVISION': '1e05',
09:11:56 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
09:11:56 INFO - 'PROGRAMFILES': 'C:\\Program Files',
09:11:56 INFO - 'PROMPT': '$P$G',
09:11:56 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
09:11:56 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
09:11:56 INFO - 'PUBLIC': 'C:\\Users\\Public',
09:11:56 INFO - 'PWD': 'C:\\slave\\test',
09:11:56 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
09:11:56 INFO - 'SLAVEDIR': 'C:\\slave\\',
09:11:56 INFO - 'SYSTEMDRIVE': 'C:',
09:11:56 INFO - 'SYSTEMROOT': 'C:\\windows',
09:11:56 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:11:56 INFO - 'TEST1': 'testie',
09:11:56 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:11:56 INFO - 'USERDOMAIN': 'T-W732-IX-142',
09:11:56 INFO - 'USERNAME': 'cltbld',
09:11:56 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
09:11:56 INFO - 'WINDIR': 'C:\\windows',
09:11:56 INFO - 'WINDOWS_TRACING_FLAGS': '3',
09:11:56 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
09:11:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
09:11:58 INFO - Ignoring indexes: https://pypi.python.org/simple/
09:11:58 INFO - Downloading/unpacking psutil>=3.1.1
09:11:58 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:11:58 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:11:59 INFO - Installing collected packages: psutil
09:11:59 INFO - Successfully installed psutil
09:11:59 INFO - Cleaning up...
09:11:59 INFO - Return code: 0
09:11:59 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv
09:11: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:11:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
09:11: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:11:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
09:11:59 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 0x018A0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01947E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0184B228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01954E90>, '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 0x0136A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F5E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-142', 'USERDOMAIN': 'T-W732-IX-142', '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-142', '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:11:59 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:11:59 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:11:59 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
09:11:59 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
09:11:59 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
09:11:59 INFO - 'COMPUTERNAME': 'T-W732-IX-142',
09:11:59 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
09:11:59 INFO - 'CYGWINBASE': 'C:\\cygwin',
09:11:59 INFO - 'DCLOCATION': 'SCL3',
09:11:59 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
09:11:59 INFO - 'FP_NO_HOST_CHECK': 'NO',
09:11:59 INFO - 'HOMEDRIVE': 'C:',
09:11:59 INFO - 'HOMEPATH': '\\Users\\cltbld',
09:11:59 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
09:11:59 INFO - 'KTS_VERSION': '1.19c',
09:11:59 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
09:11:59 INFO - 'LOGONSERVER': '\\\\T-W732-IX-142',
09:11:59 INFO - 'MONDIR': 'C:\\Monitor_config\\',
09:11:59 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
09:11:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
09:11:59 INFO - 'MOZ_NO_REMOTE': '1',
09:11:59 INFO - 'NO_EM_RESTART': '1',
09:11:59 INFO - 'NUMBER_OF_PROCESSORS': '8',
09:11:59 INFO - 'OS': 'Windows_NT',
09:11:59 INFO - 'OURDRIVE': 'C:',
09:11:59 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:11:59 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
09:11:59 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
09:11:59 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
09:11:59 INFO - 'PROCESSOR_LEVEL': '6',
09:11:59 INFO - 'PROCESSOR_REVISION': '1e05',
09:11:59 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
09:11:59 INFO - 'PROGRAMFILES': 'C:\\Program Files',
09:11:59 INFO - 'PROMPT': '$P$G',
09:11:59 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
09:11:59 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
09:11:59 INFO - 'PUBLIC': 'C:\\Users\\Public',
09:11:59 INFO - 'PWD': 'C:\\slave\\test',
09:11:59 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
09:11:59 INFO - 'SLAVEDIR': 'C:\\slave\\',
09:11:59 INFO - 'SYSTEMDRIVE': 'C:',
09:11:59 INFO - 'SYSTEMROOT': 'C:\\windows',
09:11:59 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:11:59 INFO - 'TEST1': 'testie',
09:11:59 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:11:59 INFO - 'USERDOMAIN': 'T-W732-IX-142',
09:11:59 INFO - 'USERNAME': 'cltbld',
09:11:59 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
09:11:59 INFO - 'WINDIR': 'C:\\windows',
09:11:59 INFO - 'WINDOWS_TRACING_FLAGS': '3',
09:11:59 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
09:11:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
09:12:00 INFO - Ignoring indexes: https://pypi.python.org/simple/
09:12:00 INFO - Downloading/unpacking mozsystemmonitor==0.3
09:12:00 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:12:00 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:12:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz
09:12:01 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor
09:12:01 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:12:01 INFO - Installing collected packages: mozsystemmonitor
09:12:01 INFO - Running setup.py install for mozsystemmonitor
09:12:01 INFO - Successfully installed mozsystemmonitor
09:12:01 INFO - Cleaning up...
09:12:02 INFO - Return code: 0
09:12:02 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv
09:12:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
09:12:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
09:12:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
09:12:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
09:12:02 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 0x018A0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01947E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0184B228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01954E90>, '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 0x0136A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F5E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-142', 'USERDOMAIN': 'T-W732-IX-142', '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-142', '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:12:02 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:12:02 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:12:02 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
09:12:02 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
09:12:02 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
09:12:02 INFO - 'COMPUTERNAME': 'T-W732-IX-142',
09:12:02 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
09:12:02 INFO - 'CYGWINBASE': 'C:\\cygwin',
09:12:02 INFO - 'DCLOCATION': 'SCL3',
09:12:02 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
09:12:02 INFO - 'FP_NO_HOST_CHECK': 'NO',
09:12:02 INFO - 'HOMEDRIVE': 'C:',
09:12:02 INFO - 'HOMEPATH': '\\Users\\cltbld',
09:12:02 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
09:12:02 INFO - 'KTS_VERSION': '1.19c',
09:12:02 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
09:12:02 INFO - 'LOGONSERVER': '\\\\T-W732-IX-142',
09:12:02 INFO - 'MONDIR': 'C:\\Monitor_config\\',
09:12:02 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
09:12:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
09:12:02 INFO - 'MOZ_NO_REMOTE': '1',
09:12:02 INFO - 'NO_EM_RESTART': '1',
09:12:02 INFO - 'NUMBER_OF_PROCESSORS': '8',
09:12:02 INFO - 'OS': 'Windows_NT',
09:12:02 INFO - 'OURDRIVE': 'C:',
09:12:02 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
09:12:02 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
09:12:02 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
09:12:02 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
09:12:02 INFO - 'PROCESSOR_LEVEL': '6',
09:12:02 INFO - 'PROCESSOR_REVISION': '1e05',
09:12:02 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
09:12:02 INFO - 'PROGRAMFILES': 'C:\\Program Files',
09:12:02 INFO - 'PROMPT': '$P$G',
09:12:02 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
09:12:02 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
09:12:02 INFO - 'PUBLIC': 'C:\\Users\\Public',
09:12:02 INFO - 'PWD': 'C:\\slave\\test',
09:12:02 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
09:12:02 INFO - 'SLAVEDIR': 'C:\\slave\\',
09:12:02 INFO - 'SYSTEMDRIVE': 'C:',
09:12:02 INFO - 'SYSTEMROOT': 'C:\\windows',
09:12:02 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:12:02 INFO - 'TEST1': 'testie',
09:12:02 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:12:02 INFO - 'USERDOMAIN': 'T-W732-IX-142',
09:12:02 INFO - 'USERNAME': 'cltbld',
09:12:02 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
09:12:02 INFO - 'WINDIR': 'C:\\windows',
09:12:02 INFO - 'WINDOWS_TRACING_FLAGS': '3',
09:12:02 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
09:12:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
09:12:02 INFO - Ignoring indexes: https://pypi.python.org/simple/
09:12:02 INFO - Downloading/unpacking jsonschema==2.5.1
09:12:02 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:12:02 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:12:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl
09:12:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1)
09:12:03 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:12:03 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:12:03 INFO - Downloading functools32-3.2.3-2.tar.gz
09:12:03 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32
09:12:03 INFO - warning: no files found matching '*.txt'
09:12:03 INFO - no previously-included directories found matching 'build'
09:12:03 INFO - no previously-included directories found matching 'dist'
09:12:03 INFO - no previously-included directories found matching '.git*'
09:12:03 INFO - Installing collected packages: jsonschema, functools32
09:12:03 INFO - Running setup.py install for functools32
09:12:03 INFO - warning: no files found matching '*.txt'
09:12:03 INFO - no previously-included directories found matching 'build'
09:12:03 INFO - no previously-included directories found matching 'dist'
09:12:03 INFO - no previously-included directories found matching '.git*'
09:12:03 INFO - Successfully installed jsonschema functools32
09:12:03 INFO - Cleaning up...
09:12:04 INFO - Return code: 0
09:12:04 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv
09:12: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:12:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
09:12: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:12:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
09:12: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', '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 0x018A0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01947E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0184B228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01954E90>, '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 0x0136A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F5E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-142', 'USERDOMAIN': 'T-W732-IX-142', '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-142', '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:12: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', 'functools32==3.2.3-2'] in C:\slave\test\build
09:12: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 functools32==3.2.3-2
09:12:04 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
09:12:04 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
09:12:04 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
09:12:04 INFO - 'COMPUTERNAME': 'T-W732-IX-142',
09:12:04 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
09:12:04 INFO - 'CYGWINBASE': 'C:\\cygwin',
09:12:04 INFO - 'DCLOCATION': 'SCL3',
09:12:04 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
09:12:04 INFO - 'FP_NO_HOST_CHECK': 'NO',
09:12:04 INFO - 'HOMEDRIVE': 'C:',
09:12:04 INFO - 'HOMEPATH': '\\Users\\cltbld',
09:12:04 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
09:12:04 INFO - 'KTS_VERSION': '1.19c',
09:12:04 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
09:12:04 INFO - 'LOGONSERVER': '\\\\T-W732-IX-142',
09:12:04 INFO - 'MONDIR': 'C:\\Monitor_config\\',
09:12:04 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
09:12:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
09:12:04 INFO - 'MOZ_NO_REMOTE': '1',
09:12:04 INFO - 'NO_EM_RESTART': '1',
09:12:04 INFO - 'NUMBER_OF_PROCESSORS': '8',
09:12:04 INFO - 'OS': 'Windows_NT',
09:12:04 INFO - 'OURDRIVE': 'C:',
09:12: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:12:04 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
09:12:04 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
09:12:04 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
09:12:04 INFO - 'PROCESSOR_LEVEL': '6',
09:12:04 INFO - 'PROCESSOR_REVISION': '1e05',
09:12:04 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
09:12:04 INFO - 'PROGRAMFILES': 'C:\\Program Files',
09:12:04 INFO - 'PROMPT': '$P$G',
09:12:04 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
09:12:04 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
09:12:04 INFO - 'PUBLIC': 'C:\\Users\\Public',
09:12:04 INFO - 'PWD': 'C:\\slave\\test',
09:12:04 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
09:12:04 INFO - 'SLAVEDIR': 'C:\\slave\\',
09:12:04 INFO - 'SYSTEMDRIVE': 'C:',
09:12:04 INFO - 'SYSTEMROOT': 'C:\\windows',
09:12:04 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:12:04 INFO - 'TEST1': 'testie',
09:12:04 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:12:04 INFO - 'USERDOMAIN': 'T-W732-IX-142',
09:12:04 INFO - 'USERNAME': 'cltbld',
09:12:04 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
09:12:04 INFO - 'WINDIR': 'C:\\windows',
09:12:04 INFO - 'WINDOWS_TRACING_FLAGS': '3',
09:12:04 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
09:12:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
09:12:04 INFO - Ignoring indexes: https://pypi.python.org/simple/
09:12:04 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages
09:12:04 INFO - Cleaning up...
09:12:04 INFO - Return code: 0
09:12:04 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv
09:12: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:12:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
09:12: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:12:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
09:12: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', '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 0x018A0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01947E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0184B228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01954E90>, '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 0x0136A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F5E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-142', 'USERDOMAIN': 'T-W732-IX-142', '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-142', '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:12: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', 'blobuploader==1.2.4'] in C:\slave\test\build
09:12: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 blobuploader==1.2.4
09:12:04 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
09:12:04 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
09:12:04 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
09:12:04 INFO - 'COMPUTERNAME': 'T-W732-IX-142',
09:12:04 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
09:12:04 INFO - 'CYGWINBASE': 'C:\\cygwin',
09:12:04 INFO - 'DCLOCATION': 'SCL3',
09:12:04 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
09:12:04 INFO - 'FP_NO_HOST_CHECK': 'NO',
09:12:04 INFO - 'HOMEDRIVE': 'C:',
09:12:04 INFO - 'HOMEPATH': '\\Users\\cltbld',
09:12:04 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
09:12:04 INFO - 'KTS_VERSION': '1.19c',
09:12:04 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
09:12:04 INFO - 'LOGONSERVER': '\\\\T-W732-IX-142',
09:12:04 INFO - 'MONDIR': 'C:\\Monitor_config\\',
09:12:04 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
09:12:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
09:12:04 INFO - 'MOZ_NO_REMOTE': '1',
09:12:04 INFO - 'NO_EM_RESTART': '1',
09:12:04 INFO - 'NUMBER_OF_PROCESSORS': '8',
09:12:04 INFO - 'OS': 'Windows_NT',
09:12:04 INFO - 'OURDRIVE': 'C:',
09:12: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:12:04 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
09:12:04 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
09:12:04 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
09:12:04 INFO - 'PROCESSOR_LEVEL': '6',
09:12:04 INFO - 'PROCESSOR_REVISION': '1e05',
09:12:04 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
09:12:04 INFO - 'PROGRAMFILES': 'C:\\Program Files',
09:12:04 INFO - 'PROMPT': '$P$G',
09:12:04 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
09:12:04 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
09:12:04 INFO - 'PUBLIC': 'C:\\Users\\Public',
09:12:04 INFO - 'PWD': 'C:\\slave\\test',
09:12:04 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
09:12:04 INFO - 'SLAVEDIR': 'C:\\slave\\',
09:12:04 INFO - 'SYSTEMDRIVE': 'C:',
09:12:04 INFO - 'SYSTEMROOT': 'C:\\windows',
09:12:04 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:12:04 INFO - 'TEST1': 'testie',
09:12:04 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:12:04 INFO - 'USERDOMAIN': 'T-W732-IX-142',
09:12:04 INFO - 'USERNAME': 'cltbld',
09:12:04 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
09:12:04 INFO - 'WINDIR': 'C:\\windows',
09:12:04 INFO - 'WINDOWS_TRACING_FLAGS': '3',
09:12:04 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
09:12:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
09:12:05 INFO - Ignoring indexes: https://pypi.python.org/simple/
09:12:05 INFO - Downloading/unpacking blobuploader==1.2.4
09:12:05 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:12:05 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:12:06 INFO - Downloading blobuploader-1.2.4.tar.gz
09:12:06 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader
09:12:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4)
09:12:06 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:12:06 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:12:06 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests
09:12:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4)
09:12:06 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:12:06 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:12:06 INFO - Downloading docopt-0.6.1.tar.gz
09:12:06 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt
09:12:07 INFO - Installing collected packages: blobuploader, requests, docopt
09:12:07 INFO - Running setup.py install for blobuploader
09:12:07 INFO - Running setup.py install for requests
09:12:08 INFO - Running setup.py install for docopt
09:12:08 INFO - Successfully installed blobuploader requests docopt
09:12:08 INFO - Cleaning up...
09:12:09 INFO - Return code: 0
09:12:09 INFO - Installing None into virtualenv C:\slave\test\build/venv
09:12: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:12:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
09:12: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:12:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
09:12:09 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 0x018A0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01947E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0184B228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01954E90>, '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 0x0136A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F5E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-142', 'USERDOMAIN': 'T-W732-IX-142', '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-142', '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:12:09 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:12:09 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:12:09 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
09:12:09 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
09:12:09 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
09:12:09 INFO - 'COMPUTERNAME': 'T-W732-IX-142',
09:12:09 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
09:12:09 INFO - 'CYGWINBASE': 'C:\\cygwin',
09:12:09 INFO - 'DCLOCATION': 'SCL3',
09:12:09 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
09:12:09 INFO - 'FP_NO_HOST_CHECK': 'NO',
09:12:09 INFO - 'HOMEDRIVE': 'C:',
09:12:09 INFO - 'HOMEPATH': '\\Users\\cltbld',
09:12:09 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
09:12:09 INFO - 'KTS_VERSION': '1.19c',
09:12:09 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
09:12:09 INFO - 'LOGONSERVER': '\\\\T-W732-IX-142',
09:12:09 INFO - 'MONDIR': 'C:\\Monitor_config\\',
09:12:09 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
09:12:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
09:12:09 INFO - 'MOZ_NO_REMOTE': '1',
09:12:09 INFO - 'NO_EM_RESTART': '1',
09:12:09 INFO - 'NUMBER_OF_PROCESSORS': '8',
09:12:09 INFO - 'OS': 'Windows_NT',
09:12:09 INFO - 'OURDRIVE': 'C:',
09:12: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:12:09 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
09:12:09 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
09:12:09 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
09:12:09 INFO - 'PROCESSOR_LEVEL': '6',
09:12:09 INFO - 'PROCESSOR_REVISION': '1e05',
09:12:09 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
09:12:09 INFO - 'PROGRAMFILES': 'C:\\Program Files',
09:12:09 INFO - 'PROMPT': '$P$G',
09:12:09 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
09:12:09 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
09:12:09 INFO - 'PUBLIC': 'C:\\Users\\Public',
09:12:09 INFO - 'PWD': 'C:\\slave\\test',
09:12:09 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
09:12:09 INFO - 'SLAVEDIR': 'C:\\slave\\',
09:12:09 INFO - 'SYSTEMDRIVE': 'C:',
09:12:09 INFO - 'SYSTEMROOT': 'C:\\windows',
09:12:09 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:12:09 INFO - 'TEST1': 'testie',
09:12:09 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:12:09 INFO - 'USERDOMAIN': 'T-W732-IX-142',
09:12:09 INFO - 'USERNAME': 'cltbld',
09:12:09 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
09:12:09 INFO - 'WINDIR': 'C:\\windows',
09:12:09 INFO - 'WINDOWS_TRACING_FLAGS': '3',
09:12:09 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
09:12:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
09:12:09 INFO - Ignoring indexes: https://pypi.python.org/simple/
09:12:09 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
09:12:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-p75d0s-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
09:12:09 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
09:12:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-5ghp1b-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
09:12:10 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
09:12:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-6lnw88-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
09:12:10 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
09:12:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-b6lpy2-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
09:12:10 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
09:12:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lb09ka-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
09:12:10 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
09:12:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-akgmfm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
09:12:10 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
09:12:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-r9zfhu-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
09:12:10 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
09:12:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-l2gxkf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
09:12:10 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
09:12:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-tb6smd-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
09:12:10 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
09:12:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vuntyp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
09:12:11 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
09:12:11 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-krteou-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
09:12:11 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
09:12:11 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ddbbwz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
09:12:11 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
09:12:11 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ymh2dj-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
09:12:11 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
09:12:11 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mo3wgx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
09:12:11 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
09:12:11 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-enl7kk-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
09:12:11 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
09:12:11 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xw0gks-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
09:12:11 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
09:12:11 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pixa6y-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
09:12:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
09:12:12 INFO - Running setup.py install for manifestparser
09:12:12 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts
09:12:12 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts
09:12:12 INFO - Running setup.py install for mozcrash
09:12:12 INFO - Running setup.py install for mozdebug
09:12:13 INFO - Running setup.py install for mozdevice
09:12:13 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts
09:12:13 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts
09:12:13 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts
09:12:13 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts
09:12:13 INFO - Running setup.py install for mozfile
09:12:14 INFO - Running setup.py install for mozhttpd
09:12:14 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts
09:12:14 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts
09:12:14 INFO - Running setup.py install for mozinfo
09:12:14 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts
09:12:14 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts
09:12:15 INFO - Running setup.py install for mozInstall
09:12:15 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts
09:12:15 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts
09:12:15 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts
09:12:15 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts
09:12:15 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts
09:12:15 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts
09:12:15 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts
09:12:15 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts
09:12:15 INFO - Running setup.py install for mozleak
09:12:15 INFO - Running setup.py install for mozlog
09:12:15 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts
09:12:15 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts
09:12:16 INFO - Running setup.py install for moznetwork
09:12:16 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts
09:12:16 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts
09:12:16 INFO - Running setup.py install for mozprocess
09:12:17 INFO - Running setup.py install for mozprofile
09:12:17 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts
09:12:17 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts
09:12:17 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts
09:12:17 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts
09:12:17 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts
09:12:17 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts
09:12:18 INFO - Running setup.py install for mozrunner
09:12:18 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts
09:12:18 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts
09:12:18 INFO - Running setup.py install for mozscreenshot
09:12:19 INFO - Running setup.py install for moztest
09:12:19 INFO - Running setup.py install for mozversion
09:12:19 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts
09:12:19 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts
09:12:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion
09:12:19 INFO - Cleaning up...
09:12:19 INFO - Return code: 0
09:12:19 INFO - Installing None into virtualenv C:\slave\test\build/venv
09:12:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
09:12:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
09:12:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
09:12:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
09:12:19 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 0x018A0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01947E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0184B228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01954E90>, '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 0x0136A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F5E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-142', 'USERDOMAIN': 'T-W732-IX-142', '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-142', '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:12:20 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:12:20 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:12:20 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
09:12:20 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
09:12:20 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
09:12:20 INFO - 'COMPUTERNAME': 'T-W732-IX-142',
09:12:20 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
09:12:20 INFO - 'CYGWINBASE': 'C:\\cygwin',
09:12:20 INFO - 'DCLOCATION': 'SCL3',
09:12:20 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
09:12:20 INFO - 'FP_NO_HOST_CHECK': 'NO',
09:12:20 INFO - 'HOMEDRIVE': 'C:',
09:12:20 INFO - 'HOMEPATH': '\\Users\\cltbld',
09:12:20 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
09:12:20 INFO - 'KTS_VERSION': '1.19c',
09:12:20 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
09:12:20 INFO - 'LOGONSERVER': '\\\\T-W732-IX-142',
09:12:20 INFO - 'MONDIR': 'C:\\Monitor_config\\',
09:12:20 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
09:12:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
09:12:20 INFO - 'MOZ_NO_REMOTE': '1',
09:12:20 INFO - 'NO_EM_RESTART': '1',
09:12:20 INFO - 'NUMBER_OF_PROCESSORS': '8',
09:12:20 INFO - 'OS': 'Windows_NT',
09:12:20 INFO - 'OURDRIVE': 'C:',
09:12:20 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:12:20 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
09:12:20 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
09:12:20 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
09:12:20 INFO - 'PROCESSOR_LEVEL': '6',
09:12:20 INFO - 'PROCESSOR_REVISION': '1e05',
09:12:20 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
09:12:20 INFO - 'PROGRAMFILES': 'C:\\Program Files',
09:12:20 INFO - 'PROMPT': '$P$G',
09:12:20 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
09:12:20 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
09:12:20 INFO - 'PUBLIC': 'C:\\Users\\Public',
09:12:20 INFO - 'PWD': 'C:\\slave\\test',
09:12:20 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
09:12:20 INFO - 'SLAVEDIR': 'C:\\slave\\',
09:12:20 INFO - 'SYSTEMDRIVE': 'C:',
09:12:20 INFO - 'SYSTEMROOT': 'C:\\windows',
09:12:20 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:12:20 INFO - 'TEST1': 'testie',
09:12:20 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:12:20 INFO - 'USERDOMAIN': 'T-W732-IX-142',
09:12:20 INFO - 'USERNAME': 'cltbld',
09:12:20 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
09:12:20 INFO - 'WINDIR': 'C:\\windows',
09:12:20 INFO - 'WINDOWS_TRACING_FLAGS': '3',
09:12:20 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
09:12:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
09:12:20 INFO - Ignoring indexes: https://pypi.python.org/simple/
09:12:20 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
09:12:20 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rde6c8-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
09:12:20 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:12:20 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
09:12:20 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-19eqko-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
09:12:20 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:12:20 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
09:12:20 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pmytjt-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
09:12:20 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:12:20 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
09:12:20 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kcy3tw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
09:12:20 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:12:20 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
09:12:20 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kukv1o-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
09:12:21 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:12:21 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
09:12:21 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-5zi7sa-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
09:12:21 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:12:21 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
09:12:21 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jebhcq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
09:12:21 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:12:21 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
09:12:21 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lzr_zf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
09:12:21 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:12:21 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
09:12:21 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pobiqu-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
09:12:21 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:12:21 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
09:12:21 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-8upxjo-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
09:12:21 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:12:21 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
09:12:21 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lizfss-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
09:12:21 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:12:21 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
09:12:22 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-2dtcxx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
09:12:22 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:12:22 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
09:12:22 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lpcnpq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
09:12:22 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:12:22 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
09:12:22 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kytzot-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
09:12:22 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:12:22 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
09:12:22 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qx5klb-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
09:12:22 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:12:22 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
09:12:22 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-povrzp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
09:12:22 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:12:22 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
09:12:22 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-2f2mt9-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
09:12:22 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:12:22 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:12:22 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:12:22 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:12:22 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:12:22 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:12:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10))
09:12:22 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:12:22 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:12:23 INFO - Downloading blessings-1.6.tar.gz
09:12:23 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings
09:12:24 INFO - Installing collected packages: blessings
09:12:24 INFO - Running setup.py install for blessings
09:12:24 INFO - Successfully installed blessings
09:12:24 INFO - Cleaning up...
09:12:27 INFO - Return code: 0
09:12:27 INFO - Done creating virtualenv C:\slave\test\build/venv.
09:12:27 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
09:12:27 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
09:12:27 INFO - Reading from file tmpfile_stdout
09:12:27 INFO - Using _rmtree_windows ...
09:12:27 INFO - Using _rmtree_windows ...
09:12:27 INFO - Current package versions:
09:12:27 INFO - blessings == 1.6
09:12:27 INFO - blobuploader == 1.2.4
09:12:27 INFO - docopt == 0.6.1
09:12:27 INFO - functools32 == 3.2.3-2
09:12:27 INFO - jsonschema == 2.5.1
09:12:27 INFO - manifestparser == 1.1
09:12:27 INFO - mozInstall == 1.12
09:12:27 INFO - mozcrash == 0.17
09:12:27 INFO - mozdebug == 0.1
09:12:27 INFO - mozdevice == 0.48
09:12:27 INFO - mozfile == 1.2
09:12:27 INFO - mozhttpd == 0.7
09:12:27 INFO - mozinfo == 0.9
09:12:27 INFO - mozleak == 0.1
09:12:27 INFO - mozlog == 3.4
09:12:27 INFO - moznetwork == 0.27
09:12:27 INFO - mozprocess == 0.23
09:12:27 INFO - mozprofile == 0.28
09:12:27 INFO - mozrunner == 6.12
09:12:27 INFO - mozscreenshot == 0.1
09:12:27 INFO - mozsystemmonitor == 0.3
09:12:27 INFO - moztest == 0.7
09:12:27 INFO - mozversion == 1.4
09:12:27 INFO - psutil == 3.1.1
09:12:27 INFO - requests == 1.2.3
09:12:27 INFO - Installing None into virtualenv C:\slave\test\build/venv
09:12:27 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:12:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
09:12:27 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:12:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
09:12:27 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 0x018A0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01947E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0184B228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01954E90>, '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 0x0136A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F5E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-142', 'USERDOMAIN': 'T-W732-IX-142', '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-142', '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:12:27 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:12:27 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:12:27 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
09:12:27 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
09:12:27 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
09:12:27 INFO - 'COMPUTERNAME': 'T-W732-IX-142',
09:12:27 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
09:12:27 INFO - 'CYGWINBASE': 'C:\\cygwin',
09:12:27 INFO - 'DCLOCATION': 'SCL3',
09:12:27 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
09:12:27 INFO - 'FP_NO_HOST_CHECK': 'NO',
09:12:27 INFO - 'HOMEDRIVE': 'C:',
09:12:27 INFO - 'HOMEPATH': '\\Users\\cltbld',
09:12:27 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
09:12:27 INFO - 'KTS_VERSION': '1.19c',
09:12:27 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
09:12:27 INFO - 'LOGONSERVER': '\\\\T-W732-IX-142',
09:12:27 INFO - 'MONDIR': 'C:\\Monitor_config\\',
09:12:27 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
09:12:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
09:12:27 INFO - 'MOZ_NO_REMOTE': '1',
09:12:27 INFO - 'NO_EM_RESTART': '1',
09:12:27 INFO - 'NUMBER_OF_PROCESSORS': '8',
09:12:27 INFO - 'OS': 'Windows_NT',
09:12:27 INFO - 'OURDRIVE': 'C:',
09:12:27 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
09:12:27 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
09:12:27 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
09:12:27 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
09:12:27 INFO - 'PROCESSOR_LEVEL': '6',
09:12:27 INFO - 'PROCESSOR_REVISION': '1e05',
09:12:27 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
09:12:27 INFO - 'PROGRAMFILES': 'C:\\Program Files',
09:12:27 INFO - 'PROMPT': '$P$G',
09:12:27 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
09:12:27 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
09:12:27 INFO - 'PUBLIC': 'C:\\Users\\Public',
09:12:27 INFO - 'PWD': 'C:\\slave\\test',
09:12:27 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
09:12:27 INFO - 'SLAVEDIR': 'C:\\slave\\',
09:12:27 INFO - 'SYSTEMDRIVE': 'C:',
09:12:27 INFO - 'SYSTEMROOT': 'C:\\windows',
09:12:27 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:12:27 INFO - 'TEST1': 'testie',
09:12:27 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:12:27 INFO - 'USERDOMAIN': 'T-W732-IX-142',
09:12:27 INFO - 'USERNAME': 'cltbld',
09:12:27 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
09:12:27 INFO - 'WINDIR': 'C:\\windows',
09:12:27 INFO - 'WINDOWS_TRACING_FLAGS': '3',
09:12:27 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
09:12:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
09:12:28 INFO - Ignoring indexes: https://pypi.python.org/simple/
09:12:28 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:12:28 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:12:28 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:12:28 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:12:28 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:12:28 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:12:28 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:12:28 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:12:28 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:12:28 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:12:28 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:12:28 INFO - Cleaning up...
09:12:28 INFO - Return code: 0
09:12:28 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv
09:12:28 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:12:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
09:12:28 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:12:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
09:12:28 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 0x018A0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01947E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0184B228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01954E90>, '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 0x0136A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F5E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-142', 'USERDOMAIN': 'T-W732-IX-142', '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-142', '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:12:28 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:12:28 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:12:28 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
09:12:28 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
09:12:28 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
09:12:28 INFO - 'COMPUTERNAME': 'T-W732-IX-142',
09:12:28 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
09:12:28 INFO - 'CYGWINBASE': 'C:\\cygwin',
09:12:28 INFO - 'DCLOCATION': 'SCL3',
09:12:28 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
09:12:28 INFO - 'FP_NO_HOST_CHECK': 'NO',
09:12:28 INFO - 'HOMEDRIVE': 'C:',
09:12:28 INFO - 'HOMEPATH': '\\Users\\cltbld',
09:12:28 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
09:12:28 INFO - 'KTS_VERSION': '1.19c',
09:12:28 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
09:12:28 INFO - 'LOGONSERVER': '\\\\T-W732-IX-142',
09:12:28 INFO - 'MONDIR': 'C:\\Monitor_config\\',
09:12:28 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
09:12:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
09:12:28 INFO - 'MOZ_NO_REMOTE': '1',
09:12:28 INFO - 'NO_EM_RESTART': '1',
09:12:28 INFO - 'NUMBER_OF_PROCESSORS': '8',
09:12:28 INFO - 'OS': 'Windows_NT',
09:12:28 INFO - 'OURDRIVE': 'C:',
09:12:28 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:12:28 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
09:12:28 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
09:12:28 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
09:12:28 INFO - 'PROCESSOR_LEVEL': '6',
09:12:28 INFO - 'PROCESSOR_REVISION': '1e05',
09:12:28 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
09:12:28 INFO - 'PROGRAMFILES': 'C:\\Program Files',
09:12:28 INFO - 'PROMPT': '$P$G',
09:12:28 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
09:12:28 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
09:12:28 INFO - 'PUBLIC': 'C:\\Users\\Public',
09:12:28 INFO - 'PWD': 'C:\\slave\\test',
09:12:28 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
09:12:28 INFO - 'SLAVEDIR': 'C:\\slave\\',
09:12:28 INFO - 'SYSTEMDRIVE': 'C:',
09:12:28 INFO - 'SYSTEMROOT': 'C:\\windows',
09:12:28 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:12:28 INFO - 'TEST1': 'testie',
09:12:28 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:12:28 INFO - 'USERDOMAIN': 'T-W732-IX-142',
09:12:28 INFO - 'USERNAME': 'cltbld',
09:12:28 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
09:12:28 INFO - 'WINDIR': 'C:\\windows',
09:12:28 INFO - 'WINDOWS_TRACING_FLAGS': '3',
09:12:28 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
09:12:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
09:12:28 INFO - Ignoring indexes: https://pypi.python.org/simple/
09:12:28 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages
09:12:28 INFO - Cleaning up...
09:12:28 INFO - Return code: 0
09:12:28 INFO - Running post-action listener: _resource_record_post_action
09:12:28 INFO - Running post-action listener: _start_resource_monitoring
09:12:28 INFO - Starting resource monitoring.
09:12:28 INFO - [mozharness: 2016-09-28 16:12:28.618000Z] Finished create-virtualenv step (success)
09:12:28 INFO - [mozharness: 2016-09-28 16:12:28.618000Z] Running install step.
09:12:28 INFO - Running pre-action listener: _resource_record_pre_action
09:12:28 INFO - Running main action method: install
09:12:28 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
09:12:28 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
09:12:28 INFO - Reading from file tmpfile_stdout
09:12:28 INFO - Using _rmtree_windows ...
09:12:28 INFO - Using _rmtree_windows ...
09:12:28 INFO - Detecting whether we're running mozinstall >=1.0...
09:12:28 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h']
09:12:28 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h
09:12:29 INFO - Reading from file tmpfile_stdout
09:12:29 INFO - Output received:
09:12:29 INFO - Usage: mozinstall-script.py [options] installer
09:12:29 INFO - Options:
09:12:29 INFO - -h, --help show this help message and exit
09:12:29 INFO - -d DEST, --destination=DEST
09:12:29 INFO - Directory to install application into. [default:
09:12:29 INFO - "C:\slave\test"]
09:12:29 INFO - --app=APP Application being installed. [default: firefox]
09:12:29 INFO - Using _rmtree_windows ...
09:12:29 INFO - Using _rmtree_windows ...
09:12:29 INFO - mkdir: C:\slave\test\build\application
09:12:29 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:12:29 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:12:32 INFO - Reading from file tmpfile_stdout
09:12:32 INFO - Output received:
09:12:32 INFO - C:\slave\test\build\application\firefox\firefox.exe
09:12:32 INFO - Using _rmtree_windows ...
09:12:32 INFO - Using _rmtree_windows ...
09:12:32 INFO - Running post-action listener: _resource_record_post_action
09:12:32 INFO - [mozharness: 2016-09-28 16:12:32.014000Z] Finished install step (success)
09:12:32 INFO - [mozharness: 2016-09-28 16:12:32.014000Z] Running run-tests step.
09:12:32 INFO - Running pre-action listener: _resource_record_pre_action
09:12:32 INFO - Running main action method: run_tests
09:12:32 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version']
09:12:32 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version
09:12:32 INFO - Python 2.7.3
09:12:32 INFO - Return code: 0
09:12:32 INFO - grabbing minidump binary from tooltool
09:12:32 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:12:32 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 0x01954E90>, '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 0x0136A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F5E520>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1
09:12:32 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:12:32 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:12:32 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:12:32 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'...
09:12:32 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmpumx5th
09:12:32 INFO - INFO - File integrity verified, renaming tmpumx5th to win32-minidump_stackwalk.exe
09:12:32 INFO - Return code: 0
09:12:32 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755
09:12:32 INFO - mkdir: C:\slave\test\build\blobber_upload_dir
09:12:32 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir
09:12:32 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe
09:12:32 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir
09:12:32 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fDJXzSd1Qeik6j77PXtRRg/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-142', '--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:12:32 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --branchName Autoland-Non-PGO --suite dromaeojs --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/fDJXzSd1Qeik6j77PXtRRg/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-142 --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:12:32 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
09:12:32 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
09:12:32 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
09:12:32 INFO - 'COMPUTERNAME': 'T-W732-IX-142',
09:12:32 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
09:12:32 INFO - 'CYGWINBASE': 'C:\\cygwin',
09:12:32 INFO - 'DCLOCATION': 'SCL3',
09:12:32 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
09:12:32 INFO - 'FP_NO_HOST_CHECK': 'NO',
09:12:32 INFO - 'HOMEDRIVE': 'C:',
09:12:32 INFO - 'HOMEPATH': '\\Users\\cltbld',
09:12:32 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
09:12:32 INFO - 'KTS_VERSION': '1.19c',
09:12:32 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
09:12:32 INFO - 'LOGONSERVER': '\\\\T-W732-IX-142',
09:12:32 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir',
09:12:32 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe',
09:12:32 INFO - 'MONDIR': 'C:\\Monitor_config\\',
09:12:32 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
09:12:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
09:12:32 INFO - 'MOZ_NO_REMOTE': '1',
09:12:32 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir',
09:12:32 INFO - 'NO_EM_RESTART': '1',
09:12:32 INFO - 'NUMBER_OF_PROCESSORS': '8',
09:12:32 INFO - 'OS': 'Windows_NT',
09:12:32 INFO - 'OURDRIVE': 'C:',
09:12:32 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:12:32 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
09:12:32 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
09:12:32 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
09:12:32 INFO - 'PROCESSOR_LEVEL': '6',
09:12:32 INFO - 'PROCESSOR_REVISION': '1e05',
09:12:32 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
09:12:32 INFO - 'PROGRAMFILES': 'C:\\Program Files',
09:12:32 INFO - 'PROMPT': '$P$G',
09:12:32 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
09:12:32 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
09:12:32 INFO - 'PUBLIC': 'C:\\Users\\Public',
09:12:32 INFO - 'PWD': 'C:\\slave\\test',
09:12:32 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos',
09:12:32 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
09:12:32 INFO - 'SLAVEDIR': 'C:\\slave\\',
09:12:32 INFO - 'SYSTEMDRIVE': 'C:',
09:12:32 INFO - 'SYSTEMROOT': 'C:\\windows',
09:12:32 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:12:32 INFO - 'TEST1': 'testie',
09:12:32 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:12:32 INFO - 'USERDOMAIN': 'T-W732-IX-142',
09:12:32 INFO - 'USERNAME': 'cltbld',
09:12:32 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
09:12:32 INFO - 'WINDIR': 'C:\\windows',
09:12:32 INFO - 'WINDOWS_TRACING_FLAGS': '3',
09:12:32 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
09:12:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
09:12:32 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fDJXzSd1Qeik6j77PXtRRg/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-142', '--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:12:33 INFO - mozversion application_buildid: 20160928070622
09:12:33 INFO - mozversion application_changeset: bf025a49293e21d5a46786bc05e4713f0ba4cc0f
09:12:33 INFO - mozversion application_display_name: Nightly
09:12:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
09:12:33 INFO - mozversion application_name: Firefox
09:12:33 INFO - mozversion application_remotingname: firefox
09:12:33 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland
09:12:33 INFO - mozversion application_vendor: Mozilla
09:12:33 INFO - mozversion application_version: 52.0a1
09:12:33 INFO - mozversion platform_buildid: 20160928070622
09:12:33 INFO - mozversion platform_changeset: bf025a49293e21d5a46786bc05e4713f0ba4cc0f
09:12:33 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland
09:12:33 INFO - mozversion platform_version: 52.0a1
09:12:33 INFO - using testdate: 1475079153
09:12:33 INFO - actual date: 1475079153
09:12:33 INFO - starting webserver on 'localhost:49718'
09:12:33 INFO - SUITE-START | Running 2 tests
09:12:33 INFO - TEST-START | dromaeo_css
09:12:33 INFO - operating with platform_type : w7_
09:12:33 INFO - Initialising browser for dromaeo_css test...
09:12:33 INFO - TEST-INFO | started process 1568 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpmliqwf\profile http://localhost:49718/getInfo.html)
09:12:41 INFO - PROCESS | 1568 | __metrics Screen width/height:1600/1200
09:12:41 INFO - PROCESS | 1568 | colorDepth:24
09:12:41 INFO - PROCESS | 1568 | Browser inner width/height: 1010/674
09:12:41 INFO - PROCESS | 1568 | __metrics
09:12:41 INFO - PROCESS | 1568 | 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:12:41 INFO - PROCESS | 1568 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
09:12:42 INFO - TEST-INFO | 1568: exit 0
09:12:42 INFO - Browser initialized.
09:12:42 INFO - Running cycle 1/1 for dromaeo_css test...
09:12:42 INFO - TEST-INFO | started process 3180 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpmliqwf\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1)
09:12:43 INFO - PROCESS | 3180 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0
09:12:54 INFO - PROCESS | 3180 | RSS: Main: 139530240
09:12:54 INFO - PROCESS | 3180 |
09:13:46 INFO - PROCESS | 3180 | 1475079226013 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]
09:15:59 INFO - PROCESS | 3180 | Cycle 1(1): loaded http://localhost:49718/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49718/tests/dromaeo/cssquery-ext.html)
09:16:00 INFO - PROCESS | 3180 | RSS: Main: 181043200
09:16:00 INFO - PROCESS | 3180 |
09:19:03 INFO - PROCESS | 3180 | Cycle 1(1): loaded http://localhost:49718/tests/dromaeo/cssquery-ext.html (next: http://localhost:49718/tests/dromaeo/cssquery-jquery.html)
09:19:03 INFO - PROCESS | 3180 | RSS: Main: 203657216
09:19:03 INFO - PROCESS | 3180 |
09:22:06 INFO - PROCESS | 3180 | Cycle 1(1): loaded http://localhost:49718/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49718/tests/dromaeo/cssquery-mootools.html)
09:22:06 INFO - PROCESS | 3180 | RSS: Main: 150478848
09:22:06 INFO - PROCESS | 3180 |
09:25:09 INFO - PROCESS | 3180 | Cycle 1(1): loaded http://localhost:49718/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49718/tests/dromaeo/cssquery-prototype.html)
09:25:09 INFO - PROCESS | 3180 | RSS: Main: 162910208
09:25:09 INFO - PROCESS | 3180 |
09:28:12 INFO - PROCESS | 3180 | Cycle 1(1): loaded http://localhost:49718/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49718/tests/dromaeo/cssquery-yui.html)
09:28:13 INFO - PROCESS | 3180 | RSS: Main: 141807616
09:28:13 INFO - PROCESS | 3180 |
09:31:16 INFO - PROCESS | 3180 | Cycle 1(1): loaded http://localhost:49718/tests/dromaeo/cssquery-yui.html (next: http://localhost:49718/tests/dromaeo/cssquery-dojo.html)
09:31:16 INFO - PROCESS | 3180 | RSS: Main: 192319488
09:31:16 INFO - PROCESS | 3180 |
09:31:16 INFO - PROCESS | 3180 | __start_tp_report
09:31:16 INFO - PROCESS | 3180 | _x_x_mozilla_page_load
09:31:16 INFO - PROCESS | 3180 | _x_x_mozilla_page_load_details
09:31:16 INFO - PROCESS | 3180 | |i|pagename|runs|
09:31:16 INFO - PROCESS | 3180 | |0;dojo.html;2346.718393792745;2368.8933997970094;2380.297812145417;2382.368672301839;2389.7490763469823;3984.123492831576;4013.1772986537762;4015.4177644241568;4061.959380406195;4085.448464457297;3938.094238325187;3947.7236593438397;3992.3213053780855;3993.261246669361;4020.4572382728325;3513.2270900401872;3525.9069688396553;3551.12997315658;3595.7303202259936;3645.8906232813056;3436.467347561132;3452.8100954447495;3457.8443970021285;3517.70099541538;3521.313343897928;3553.1117220694828;3583.7133029357587;3589.9282014359696;3617.764845285048;3631.5642122945283;3468.3930312195516;3537.6285490023433;3553.6091029986683;3556.590992035923;3595.083253770298;3495.370833250014;3497.2131270464197;3517.6482351764876;3558.5018097466377;3605.188832512664;3891.6497515223764;3941.4481972523868;3945.0926286954164;3990.142119444304;4060.20825938942;4113.91772164558;4155.252054630166;4215.957840421587;4269.615734583871;4297.462817147856;4212.810423530917;4213.367994800774;4233.597808208216;4247.893802654928;4299.14017196562;3805;3905.6094390560716;3932.8623498177712;3957.5053118360206;3998.4002399639994;4213.052063285798;4276.486821581368;4334.068175342317;4346.7826608670075;4352.434183556181;4100.897477563025;4145.253854306254;4179.164167166578;4179.477565304337;4224.514180869178;4139.855105071308;4194.182134483746;4279.828806847753;4280.101178752497;4288.549702281278;4173.08192197716;4248.044190057212;4248.468941382327;4256.957045523866;4260.233158031522;4041.9797901010306;4046.4941882264716;4113.341865301538;4168.958310416918;4174.707770456039;2942.955855662167;2959.689232630586;2968.5992391027366;2999.5350720638335;3005.8046226995175;2640.8019398545184;2645.1799942018038;2691.327168207948;2702.459508098388;2715.7420045095682;3911.2764138634443;3963.1677347757295;3976.483057202603;3997;3999.440078389028;4177.143685544456;4202.495700515958;4207.957920420818;4212.641925436372;4241.766702831313;3974.205158968218;3982.5818289079807;4051.6353528182603;4072.2669919414197;4080.81636326366;3552.964470355315;3621.72837037218;3641.3809652359164;3699.186179040553;3699.5930447650735;4157.688173387069;4230.2174097792;4276.358546218092;4323.243432399255;4423.62399196072;4219.936700949427;4225.302825033835;4256.063665993476;4347.608715215646;4348.347747837724;4255.489341279066;4313.590208930146;4332.458442694663;4344.174606824693;4352.825886964612;4107.774072426046;4150.066235097079;4150.647194988339;4160.854370097032;4180.912962629677;4184.83260669582;4224.852130175429;4232.936505952474;4267.807948642257;4268.061026574149;4020.0552870074926;4037.9596204038758;4040.717149799486;4102.425660407485;4147.4815648044;3936.9409458858713;4006.579309172495;4012.25773231953;4032.5362583303295;4088.6933479989716;3986.760570390533;4039.2527382434346;4096.139810639799;4101.05675694586;4107.609777071173;4117.691173162085;4198.433211516528;4207.158568286416;4213.578642135884;4215.3044747616295;4002.199560087936;4033.878983630496;4035.616616421435;4046.2918989177365;4065.0853557949226
09:31:16 INFO - PROCESS | 3180 | |1;ext.html;524.3157679228607;530.8646295194725;536.5841472858535;559.504838218177;578.0548802707577;25209.62185567218;35538.64461355386;36744.81627591865;36824.079398015;39853;35149.82425087877;35966.28067438649;36228;36625.450618240684;36683.4497482538;6403.871922561546;7617.809554761134;7727.768166954972;7797.103333116679;7800.687972481094;6452.580582262161;9607.2314214863;9709.70870873875;9820.607175712963;10216.74458138545;9582.856257156149;9889.75275618108;9919.950400248024;9925.602975880956;9931.751706207366;9995.650152244672;10011.74970625733;10065.194784417266;10065.597376104946;10067.395956242648;6354.7458101675875;6530.8040758777315;9078.182963533314;9220.953895230481;9323.021082786345;34385.14037149066;34800.82599586986;35095.29809403798;35113.824430877685;35493.290134197174;2740.1916434651826;2777.028040185939;2809.6487939007625;2816.9859150704115;2887.191586355824;26008;26202;26222.737772622222;26462.867685661447;26535.601965970334;3016.146544081758;3076.1386811692537;3142.1830324115895;3175.841207939594;3177.856996435166;228728;229024.85487572622;229416;230248;230256;41769.58230417657;45599.31601025987;45608.3158752619;45678.5432145681;45680.3147952781;24901;28467.857660711565;48676.75661621669;48715;48811.75594122078;11528.76942461146;11781.882181178249;11814.409279535988;11819.822702659467;11822.054235661126;7035.613041282779;8734.69428569997;8746;8798.868016979623;8801.207891289814;13157.210567365988;15371.692566148615;15710;15887;16085.35658573644;6354.3963323484195;6397.392247736458;6413;6450.677466126769;6487.837804054936;744.1107876088023;911.9407238529475;943.2124176312856;958.4488918871538;984.8128855517429;24526.386840329134;25200.495990080097;25240.368990775376;25432.364190895376;25492.74507254941;796.016919104905;842.6882053640193;863.6631713631689;889.4485419040236;966.1256562810668;12960.416781244867;13540.119892206974;13751.931240343734;13809.861901381058;13840.238786866617;224751;298118;298781;299004;300175;42729.14541709149;43301;43568;43716.12567748627;43828.3425748614;975.7656564445981;992.6823416506793;1081.9296745711504;1105.4086063955745;1116.7431490757172;23777.524449510565;25216.719423743325;25768.61347079758;25873.482530349665;26221.606675900257;37010.25979480449;37201.627983719816;37429.064273393386;37508;37543;253602;254536;259817;260595;262496;19960.60078798445;20174.899125504864;20529.076191571712;20889.37331880046;21422.571548569253;37913.62086379101;37987.620123798406;38035.42946855855;38038;38091;30867;32260.516092259106;32306.192345191557;32662.83668581642;32722.50916236211;31928.201794955312;32211.194720132185;32616.836915816217;32622.673773261962;33209.83395083009;30966.53550196794;31604;34079.82960085184;34091.14772130716;34109.82945085358;28774;30320;31518.212044699067;32531.674683252866;32730.836345819072;21427;23437;23482.683650374627;23494.412639684146;23540.411489712893
09:31:16 INFO - PROCESS | 3180 | |2;jquery.html;291.8380298934093;320.9261869769953;325.99837000814995;331.32079237563;332.7820277718094;11300.152488563359;15516.689666206668;15652.765208521882;15816.604584885383;16873.24070416831;14073.677843155867;15085.472008479705;15113.622159446018;15137.848621513782;15489.302981365838;4647.117047760931;4965.751712414365;5027.84916452507;5074.340335756347;5098.311727916739;3519.8064106474135;3898.9805050974705;4977.800887964495;5057.721825299607;5079.187330027196;3706.184639379332;5882.529397648199;5912.467877890989;5957.255343082114;6005.729742161591;5807.1579620954935;5815.6801375924515;5959.970200149015;5971.880562388771;5975.492083172936;4284.657227421799;4492.73043617384;4548.999220171557;4570.154521413549;4575.771211439448;9165.358424910259;10147.238957078249;10280;10281.228907831868;10298.691039268833;1539.7844301797757;1546.2964351220169;1581.2726145972865;1588.2932095217632;1588.9841101588952;7575.734849280248;7756.108047574488;7981.241782030698;7993.840123197503;8057.879131813028;1526.175865032881;1560.2432820082252;1586.6192113892698;1626.7397216445313;1629.1528405229333;54302.728486357315;63637.04544431837;65677.35806604825;66487;67657;8624.008239052464;8725.563721814011;8750.343724220582;8817.942578998407;9018.684346047989;11508.367039812723;11533.923893699048;11762.764744705059;11882.40587970598;11931.224470409396;6193.969030154821;6202.782902598387;6256.0303153011355;6354.809355719243;6442.871142577123;6018.488428483541;6093.512518998469;6099.2985806632805;6190.102435146879;6198.969005154945;3787.242551489713;3796.886093417202;3797.1646237827636;3808.3906574947873;3870.245302166051;1572.5046610317943;1605.5309391906508;1616.0788350640023;1633.8774591905656;1641.8193998660138;610.4292486525025;757.640120942559;783.6943591999125;836.9079401265856;870.1255238485409;15725.292361843745;15777.290021948811;16216.189190540426;16387.5903102421;16676.58308542296;773.6402572803673;786.7207141464812;792.9920700792918;817.0767033252506;820.4953953318785;8668.696595619123;9084.137006984325;9150.313726470542;9153.313501487415;9428.151466367923;277107;278229;279698;283410;284911;63061;64958.67520662461;68220;70000;71578.2842171582;764.2052265643669;797.2744802229943;799.8840168175582;808.1272225995792;808.7978005498626;14548.85451145475;14884.851151488347;15062.171580563174;15081.39674413055;15167.620809479851;36631.633683662825;36969.63030369662;37821.243575128894;38741.806290968365;39380.80309598434;48253;49914.750426247636;51611.741941291555;51796;52931.735341323045;6230.2231892264335;6328.177336946167;6366.9044964326495;6369.840753981188;6442.420182183626;9420.246380289424;10662.46687665598;10774.515146818569;10835.566577337133;11198.992090711876;8229.094799571923;8675.86986195195;9082.545872706207;9118.221946720327;9314.906850931675;8749.65001399937;8796;8875;9169.679061232824;9473.810523789623;8939.776505587151;9002.369834111549;9056.773580660536;9313.860292095489;9358.4384936904;6394.616323020634;6470.22357317125;6559.180102487189;6564.113844630913;6675.265720770614;8719.607617657093;8742.912570874465;8949.776255593662;9018.23345015681;9102.362834601514
09:31:16 INFO - PROCESS | 3180 | |3;mootools.html;1210.4794938176583;1212.5937810833375;1266.0378112634398;1505.751550994086;1514.5380658899032;6285.402886725759;6536.901946470807;6550.410463058324;6564.212294524652;6680.131582894217;5552.77788888445;5747.856303592413;6221.813345599628;6473.838154046151;6789.5926244425245;1861.2368928739254;1881.9153138108818;1885.2270569066686;1887.207372903377;1887.5658598522348;1821.280594165304;1848.5563464768427;1853.1105069566615;1856.33172058059;1870.093004892626;1589.0306912783194;1641.6880792649417;1774.3612299572144;1824.5894673698378;1830.121541660004;1831.6611426886002;1838.264694122355;1845.049799353334;1849.8612604054615;1853.7960824309316;1811.856359833874;1823.6990896502061;1831.5146486181172;1833.1384249402759;1834.1654547181001;6579.34206579343;6706.865862682768;6744.629045402501;6748.089007983957;6753.662316884185;3779.7543159694533;3783.5081439412975;3789.412641040643;3793.9810300948593;3820.7516511426948;2309.8267629927823;2325.476767727265;2358.752331005254;2404.1825779235146;2457.5576396248666;763.335897768939;770.6455030685892;787.8384931089171;794.3724457678427;797.50554656113;8113.959430202811;8369.83260334796;8388.161183881562;8388.161183881624;8412.242898139135;3408.199073217792;3438.7249020078334;3465.237647717498;3495;3516.0682419158943;7377.852442951111;7383.520071195355;7400.074990626172;7408.629568521553;7411.85176296471;3881.4565960765517;3942.7240093193773;3943.4084887266567;3952.644262016375;3961.088949541564;3437.7937323760652;3507.2985402919007;3507.94738078929;3516.331896939573;3523.119220194951;2876.6404199475064;3079.0916679579577;3111.0977816432983;3115;3121.843907804601;975.7804493988938;1007.7682133109279;1020.1277907389075;1021.4075836015094;1024.99487502562;593.4125216036093;704.5631708340861;722.1045903080143;748.259223368861;761.7600455856387;4076.3885417187066;4212.620864122244;4228.281192197333;4280.914381712349;4305.655547556188;749.1197842534999;766.432839698619;774.4849674966084;777.4790890103644;814.0475643497123;2766.61267422561;2792.6509186351705;2797.2307415658756;2808.2838876086876;3006.0380678182773;7503.812404689927;7635.427342949301;7847.097583777939;8021.2379823916635;8023.719169829028;7681.961590192125;7984.121746607869;8006.919930800617;8017.639206235734;8032.558209298428;6088.056351265473;6125.173101631222;6135.110408990671;6249.375062493714;6310.337414571496;4309.6767742418815;4394.63766232469;4440.689151759346;4482.955170448384;4649.3026046093355;7142.285771422816;7431.145418276751;7468.551886886804;7501.549907005598;7546.169921308761;7299.270072992658;7587.506812057199;7590.658420371207;7609.847803043797;7611.467197296136;5650.576206784425;5669.518090962314;5686.914696279477;5751.683657398883;5773.711314434346;6011.759529618941;6249.125122482765;6252.374762523893;6276;6295.055741638791;4963.354763880672;5268;5639.097744360883;5667.20659107717;5704.629199102045;5491.588130890119;5512.5314348280845;5519.420460851553;5555.250041244381;5635.295588051494;5109.463506331782;5118.362124120245;5200.089984252816;5228.1112210923475;5323.520883120537;3530.152763336729;3657.359962006585;3667.9449808252366;3687.870924517629;3759.755615884959;3511.438169892805;3550.165711057951;3612.927741445104;3619.837107330229;3634.4548317752547
09:31:16 INFO - PROCESS | 3180 | |4;prototype.html;236.34532345403233;237.1072910491999;248.6531288852051;248.68541295261485;250.15697100770407;4335.631471324938;4362.367456718778;4377.868663940087;4383.079553293805;4407.206702793496;4087.8569250076253;4143.854965076223;4270.850520231793;4314.697971142022;4339.392485052095;3247.8213698246586;3256.951145732804;3257.820819854907;3264.3144939562603;3273.705366517013;3248.691374319436;3261.8858339957987;3262.135534083458;3265.689759472858;3266.7549933754945;3045.11691609433;3083.6453807812054;3152.542881282213;3185.5540224368606;3237.2230664640433;3135.6394014688376;3139.9481173806803;3207.262329664167;3217.4852023676335;3238.141892398493;2717.5108480473505;2759.0343379817105;2764.1292992707436;2831.405404864981;2833.5183018886837;4157.563455837124;4217.40956266123;4241.554636763157;4255.234057869582;4261.2329780639475;3680.9447858282147;3694.390425579776;3785.413260944558;3789.886303410902;3790.692211187136;4219.641330486912;4431.357453169272;4471.329300604935;4475.39582156408;4484.910301793946;3095.349976504937;3164.8290132650836;3172.7144556989983;3177.1104090854597;3177.904662860095;4598.931016034762;4631.907361852744;4641.744704041243;4664.440267167927;4679;3172.98413507931;3233.207864073317;3245.3022600141085;3245.594300712411;3246.0910944935454;3277.754168437377;3283.7044665980175;3288.6650478160773;3288.753343499247;3290.7038366547126;3436.9484457733156;3682.5396825396824;3750.249950010009;3771.830267637963;3775;4442.777861106996;4525.751083690363;4529.139463501924;4536.637069034469;4542.433525058332;1129.3111202166672;1136.8465257190173;1137.0619239127586;1138.9031932285682;1142.7885841119419;736.2563810551275;754.5435011817881;756.5687558091835;761.9390448764196;781.8788087846393;4223.303154979455;4239.703220774516;4245.702800803976;4305;4307.418498502725;4477.306017567283;4484.2376795944765;4498.3252512123445;4526.932096018562;4586.908261834811;4305.31115021595;4327.307630779061;4332.588404101605;4338.006775325016;4353.390525326457;2354.7645235476316;2431.565376427916;2456;2459.3974476253434;2462.187478132212;4789.616830653539;4828.903421931542;4842.685225460334;4849.975750121227;4857.955539559011;4753.667243292936;4774.188387974053;4788.209945359046;4790.0659371422935;4792.496787837296;4720.91418973638;4749.35883655702;4838.443578988461;4844.733539655353;4850.781714822841;4390.363397307437;4526.253168227206;4617.607503362117;4636.281376386665;4679.438467383936;4503.099380123923;4605.677602567788;4625.838095666635;4626.8149274028665;4627.657979185999;4838.104950584153;4855.635827312895;4857.805687772449;4864.0028794096415;4874.512548745097;3091.829949352807;3127.9687203128587;3138.10563989262;3142.1987393214586;3159.921001974969;4638.327442520748;4684.414448193976;4684.531546845288;4691.882702932454;4694.976525117352;4235.512916014698;4492.483364413134;4497.527759585197;4505.414296141481;4509.452159529855;4491.169133710274;4491.865244042684;4492.663050271178;4495.269321311253;4496.527864574173;4289;4369.803358848796;4404.713693609905;4423.292273236396;4435.3125265583885;4492.707973981681;4495.168393847149;4548.499665036909;4550.067236216634;4557.817687292471;4342.457192850894;4410.140022695544;4476.679379582965;4476.955230447654;4487.304467807495
09:31:16 INFO - PROCESS | 3180 | |5;yui.html;583.2417856786178;587.8706684529404;599.388623603924;625.5996162456029;678.8167194857392;11835.171537992344;12808.103432759688;12821.807672884914;12827.551035713752;12860.871391286084;12048.81926771097;12125.514979400836;12174.81737773934;12190.329531875743;12535.874641253584;654.774102934488;668.3951024322995;673.6093066021721;673.6227712481002;673.6901025528239;512.1191550533077;525.9684418934876;639.9520035997296;659.1694464974144;660.808365573983;704.2570088556571;707.285641502084;709.5352544083634;710.3393843725333;712.4122598855938;686.2417029182731;698.9755358562477;704.1093017333078;705.6894966214849;708.4580296073508;367.32229037426174;384.8710681921532;487.99268010979506;499.64275542986576;508.2655562711883;11935.104867135;11943.581974630846;12469.937650311782;12486.313252771093;12486.375681215903;11175.385353805537;11621.186516943817;11627.59303424376;11917.642470725805;12100.697482562919;9319.16127548517;9775.364601300964;10471;10681.41252231127;10980.121590272758;3078.1996680862912;3087.8456077196274;3165.4618714818534;3217.147455924182;3271.2312606537444;57965;63633;63972.68013659902;64304.67847660732;64326;9137.543122843832;9329.90670093304;9530.618775248913;9648.421094734198;9835.41027511569;17444.738328925076;17597.12014399275;17642.76573622347;17645.558861028578;17679.292828286983;6428.325025872309;6467.482601391877;6469.611823290617;6487.3836985486305;6605.943049112215;3323.21904352477;3397.978202969229;3522.577290725078;3586.085548185196;3610.7472476926887;2410.975335482413;2502.724700282967;2577.085134777127;2579.471208402273;2584.767370936625;412.4617374326473;429.98925026874576;433.4711651784817;434.4482507215818;435.39697518936504;444.8487514245172;510.3926327670061;542.4304480295674;577.7357927708481;602.2983224543445;7254.818629534198;7285.453590980697;7467.738629147953;7481.663325150382;7520;535.2212530767723;547.3486551004293;553.5765139668157;555.686037388874;615.6767696959132;3490.406630872758;3505.4391297392294;3573.94639080414;3594.9101272467874;3663.670269675742;69740;72116.59125565604;72366.27633723595;81219;84399;74188;75666.24333756592;75889;75989;76593.6170319156;57901.42098579044;60575.69712151411;67421;68430.13053498912;69073.30926690769;7695.115061767969;7832.670192393658;8041.155678653891;8052.396070294634;8052.718154864551;41491.7925410371;42766;43086.78456607697;43770.12459750724;43826.561734382245;53803;56919;59274.40725592689;60085.39914600798;60461;9671.516424178904;9908.108270255423;10226.284160109019;10230.437325947145;10502.579896804347;11960.880391195977;11991.460384282555;12093.213941093802;12195.451204695988;12273.938630306791;11292.887071129184;11344.546218151512;11387.373694446886;11500.367479788692;11587.188896777145;10697.946510267398;10814.513346899252;10941.179411544006;10996.28524145928;11092.057175140204;10507.056194604023;10709.196810239107;10789.892101078889;10896.12830973536;11051.668449946514;9261.212796912338;9921.305508614329;10036.739231218029;10171.084602385821;10318.742031449274;8917.643294268417;8954.776130596787;9103.772405689911;9176.128267814547;9422.62309507612
09:31:16 INFO - PROCESS | 3180 | __end_tp_report
09:31:16 INFO - PROCESS | 3180 | __start_cc_report
09:31:16 INFO - PROCESS | 3180 | _x_x_mozilla_cycle_collect,892
09:31:16 INFO - PROCESS | 3180 | __end_cc_report
09:31:16 INFO - PROCESS | 3180 | __startTimestamp1475080276425__endTimestamp
09:31:16 INFO - PROCESS | 3180 |
09:31:16 INFO - PROCESS | 3180 | ------- Summary: start -------
09:31:16 INFO - PROCESS | 3180 | Number of tests: 6
09:31:16 INFO - PROCESS | 3180 |
09:31:16 INFO - PROCESS | 3180 | [#0] dojo.html Cycles:165 Average:3898.29 Median:4046.29 stddev:466.24 (11.5%) stddev-sans-first:451.51
09:31:16 INFO - PROCESS | 3180 | Values: 2346.7 2368.9 2380.3 2382.4 2389.7 3984.1 4013.2 4015.4 4062.0 4085.4 3938.1 3947.7 3992.3 3993.3 4020.5 3513.2 3525.9 3551.1 3595.7 3645.9 3436.5 3452.8 3457.8 3517.7 3521.3 3553.1 3583.7 3589.9 3617.8 3631.6 3468.4 3537.6 3553.6 3556.6 3595.1 3495.4 3497.2 3517.6 3558.5 3605.2 3891.6 3941.4 3945.1 3990.1 4060.2 4113.9 4155.3 4216.0 4269.6 4297.5 4212.8 4213.4 4233.6 4247.9 4299.1 3805.0 3905.6 3932.9 3957.5 3998.4 4213.1 4276.5 4334.1 4346.8 4352.4 4100.9 4145.3 4179.2 4179.5 4224.5 4139.9 4194.2 4279.8 4280.1 4288.5 4173.1 4248.0 4248.5 4257.0 4260.2 4042.0 4046.5 4113.3 4169.0 4174.7 2943.0 2959.7 2968.6 2999.5 3005.8 2640.8 2645.2 2691.3 2702.5 2715.7 3911.3 3963.2 3976.5 3997.0 3999.4 4177.1 4202.5 4208.0 4212.6 4241.8 3974.2 3982.6 4051.6 4072.3 4080.8 3553.0 3621.7 3641.4 3699.2 3699.6 4157.7 4230.2 4276.4 4323.2 4423.6 4219.9 4225.3 4256.1 4347.6 4348.3 4255.5 4313.6 4332.5 4344.2 4352.8 4107.8 4150.1 4150.6 4160.9 4180.9 4184.8 4224.9 4232.9 4267.8 4268.1 4020.1 4038.0 4040.7 4102.4 4147.5 3936.9 4006.6 4012.3 4032.5 4088.7 3986.8 4039.3 4096.1 4101.1 4107.6 4117.7 4198.4 4207.2 4213.6 4215.3 4002.2 4033.9 4035.6 4046.3 4065.1
09:31:16 INFO - PROCESS | 3180 |
09:31:16 INFO - PROCESS | 3180 | [#1] ext.html Cycles:180 Average:40002.67 Median:32726.67 stddev:67640.93 (206.7%) stddev-sans-first:67765.73
09:31:16 INFO - PROCESS | 3180 | Values: 524.3 530.9 536.6 559.5 578.1 25209.6 35538.6 36744.8 36824.1 39853.0 35149.8 35966.3 36228.0 36625.5 36683.4 6403.9 7617.8 7727.8 7797.1 7800.7 6452.6 9607.2 9709.7 9820.6 10216.7 9582.9 9889.8 9920.0 9925.6 9931.8 9995.7 10011.7 10065.2 10065.6 10067.4 6354.7 6530.8 9078.2 9221.0 9323.0 34385.1 34800.8 35095.3 35113.8 35493.3 2740.2 2777.0 2809.6 2817.0 2887.2 26008.0 26202.0 26222.7 26462.9 26535.6 3016.1 3076.1 3142.2 3175.8 3177.9 228728.0 229024.9 229416.0 230248.0 230256.0 41769.6 45599.3 45608.3 45678.5 45680.3 24901.0 28467.9 48676.8 48715.0 48811.8 11528.8 11781.9 11814.4 11819.8 11822.1 7035.6 8734.7 8746.0 8798.9 8801.2 13157.2 15371.7 15710.0 15887.0 16085.4 6354.4 6397.4 6413.0 6450.7 6487.8 744.1 911.9 943.2 958.4 984.8 24526.4 25200.5 25240.4 25432.4 25492.7 796.0 842.7 863.7 889.4 966.1 12960.4 13540.1 13751.9 13809.9 13840.2 224751.0 298118.0 298781.0 299004.0 300175.0 42729.1 43301.0 43568.0 43716.1 43828.3 975.8 992.7 1081.9 1105.4 1116.7 23777.5 25216.7 25768.6 25873.5 26221.6 37010.3 37201.6 37429.1 37508.0 37543.0 253602.0 254536.0 259817.0 260595.0 262496.0 19960.6 20174.9 20529.1 20889.4 21422.6 37913.6 37987.6 38035.4 38038.0 38091.0 30867.0 32260.5 32306.2 32662.8 32722.5 31928.2 32211.2 32616.8 32622.7 33209.8 30966.5 31604.0 34079.8 34091.1 34109.8 28774.0 30320.0 31518.2 32531.7 32730.8 21427.0 23437.0 23482.7 23494.4 23540.4
09:31:16 INFO - PROCESS | 3180 |
09:31:16 INFO - PROCESS | 3180 | [#2] jquery.html Cycles:180 Average:19935.17 Median:5811.42 stddev:47063.92 (809.9%) stddev-sans-first:47172.83
09:31:16 INFO - PROCESS | 3180 | Values: 291.8 320.9 326.0 331.3 332.8 11300.2 15516.7 15652.8 15816.6 16873.2 14073.7 15085.5 15113.6 15137.8 15489.3 4647.1 4965.8 5027.8 5074.3 5098.3 3519.8 3899.0 4977.8 5057.7 5079.2 3706.2 5882.5 5912.5 5957.3 6005.7 5807.2 5815.7 5960.0 5971.9 5975.5 4284.7 4492.7 4549.0 4570.2 4575.8 9165.4 10147.2 10280.0 10281.2 10298.7 1539.8 1546.3 1581.3 1588.3 1589.0 7575.7 7756.1 7981.2 7993.8 8057.9 1526.2 1560.2 1586.6 1626.7 1629.2 54302.7 63637.0 65677.4 66487.0 67657.0 8624.0 8725.6 8750.3 8817.9 9018.7 11508.4 11533.9 11762.8 11882.4 11931.2 6194.0 6202.8 6256.0 6354.8 6442.9 6018.5 6093.5 6099.3 6190.1 6199.0 3787.2 3796.9 3797.2 3808.4 3870.2 1572.5 1605.5 1616.1 1633.9 1641.8 610.4 757.6 783.7 836.9 870.1 15725.3 15777.3 16216.2 16387.6 16676.6 773.6 786.7 793.0 817.1 820.5 8668.7 9084.1 9150.3 9153.3 9428.2 277107.0 278229.0 279698.0 283410.0 284911.0 63061.0 64958.7 68220.0 70000.0 71578.3 764.2 797.3 799.9 808.1 808.8 14548.9 14884.9 15062.2 15081.4 15167.6 36631.6 36969.6 37821.2 38741.8 39380.8 48253.0 49914.8 51611.7 51796.0 52931.7 6230.2 6328.2 6366.9 6369.8 6442.4 9420.2 10662.5 10774.5 10835.6 11199.0 8229.1 8675.9 9082.5 9118.2 9314.9 8749.7 8796.0 8875.0 9169.7 9473.8 8939.8 9002.4 9056.8 9313.9 9358.4 6394.6 6470.2 6559.2 6564.1 6675.3 8719.6 8742.9 8949.8 9018.2 9102.4
09:31:16 INFO - PROCESS | 3180 |
09:31:16 INFO - PROCESS | 3180 | [#3] mootools.html Cycles:180 Average:4243.06 Median:5113.91 stddev:2346.29 (45.9%) stddev-sans-first:2341.80
09:31:16 INFO - PROCESS | 3180 | Values: 1210.5 1212.6 1266.0 1505.8 1514.5 6285.4 6536.9 6550.4 6564.2 6680.1 5552.8 5747.9 6221.8 6473.8 6789.6 1861.2 1881.9 1885.2 1887.2 1887.6 1821.3 1848.6 1853.1 1856.3 1870.1 1589.0 1641.7 1774.4 1824.6 1830.1 1831.7 1838.3 1845.0 1849.9 1853.8 1811.9 1823.7 1831.5 1833.1 1834.2 6579.3 6706.9 6744.6 6748.1 6753.7 3779.8 3783.5 3789.4 3794.0 3820.8 2309.8 2325.5 2358.8 2404.2 2457.6 763.3 770.6 787.8 794.4 797.5 8114.0 8369.8 8388.2 8388.2 8412.2 3408.2 3438.7 3465.2 3495.0 3516.1 7377.9 7383.5 7400.1 7408.6 7411.9 3881.5 3942.7 3943.4 3952.6 3961.1 3437.8 3507.3 3507.9 3516.3 3523.1 2876.6 3079.1 3111.1 3115.0 3121.8 975.8 1007.8 1020.1 1021.4 1025.0 593.4 704.6 722.1 748.3 761.8 4076.4 4212.6 4228.3 4280.9 4305.7 749.1 766.4 774.5 777.5 814.0 2766.6 2792.7 2797.2 2808.3 3006.0 7503.8 7635.4 7847.1 8021.2 8023.7 7682.0 7984.1 8006.9 8017.6 8032.6 6088.1 6125.2 6135.1 6249.4 6310.3 4309.7 4394.6 4440.7 4483.0 4649.3 7142.3 7431.1 7468.6 7501.5 7546.2 7299.3 7587.5 7590.7 7609.8 7611.5 5650.6 5669.5 5686.9 5751.7 5773.7 6011.8 6249.1 6252.4 6276.0 6295.1 4963.4 5268.0 5639.1 5667.2 5704.6 5491.6 5512.5 5519.4 5555.3 5635.3 5109.5 5118.4 5200.1 5228.1 5323.5 3530.2 3657.4 3667.9 3687.9 3759.8 3511.4 3550.2 3612.9 3619.8 3634.5
09:31:16 INFO - PROCESS | 3180 |
09:31:16 INFO - PROCESS | 3180 | [#4] prototype.html Cycles:180 Average:3760.22 Median:4336.82 stddev:1138.83 (26.3%) stddev-sans-first:1110.89
09:31:16 INFO - PROCESS | 3180 | Values: 236.3 237.1 248.7 248.7 250.2 4335.6 4362.4 4377.9 4383.1 4407.2 4087.9 4143.9 4270.9 4314.7 4339.4 3247.8 3257.0 3257.8 3264.3 3273.7 3248.7 3261.9 3262.1 3265.7 3266.8 3045.1 3083.6 3152.5 3185.6 3237.2 3135.6 3139.9 3207.3 3217.5 3238.1 2717.5 2759.0 2764.1 2831.4 2833.5 4157.6 4217.4 4241.6 4255.2 4261.2 3680.9 3694.4 3785.4 3789.9 3790.7 4219.6 4431.4 4471.3 4475.4 4484.9 3095.3 3164.8 3172.7 3177.1 3177.9 4598.9 4631.9 4641.7 4664.4 4679.0 3173.0 3233.2 3245.3 3245.6 3246.1 3277.8 3283.7 3288.7 3288.8 3290.7 3436.9 3682.5 3750.2 3771.8 3775.0 4442.8 4525.8 4529.1 4536.6 4542.4 1129.3 1136.8 1137.1 1138.9 1142.8 736.3 754.5 756.6 761.9 781.9 4223.3 4239.7 4245.7 4305.0 4307.4 4477.3 4484.2 4498.3 4526.9 4586.9 4305.3 4327.3 4332.6 4338.0 4353.4 2354.8 2431.6 2456.0 2459.4 2462.2 4789.6 4828.9 4842.7 4850.0 4858.0 4753.7 4774.2 4788.2 4790.1 4792.5 4720.9 4749.4 4838.4 4844.7 4850.8 4390.4 4526.3 4617.6 4636.3 4679.4 4503.1 4605.7 4625.8 4626.8 4627.7 4838.1 4855.6 4857.8 4864.0 4874.5 3091.8 3128.0 3138.1 3142.2 3159.9 4638.3 4684.4 4684.5 4691.9 4695.0 4235.5 4492.5 4497.5 4505.4 4509.5 4491.2 4491.9 4492.7 4495.3 4496.5 4289.0 4369.8 4404.7 4423.3 4435.3 4492.7 4495.2 4548.5 4550.1 4557.8 4342.5 4410.1 4476.7 4477.0 4487.3
09:31:16 INFO - PROCESS | 3180 |
09:31:16 INFO - PROCESS | 3180 | [#5] yui.html Cycles:180 Average:16128.56 Median:511.26 stddev:22209.52 (4344.1%) stddev-sans-first:22241.15
09:31:16 INFO - PROCESS | 3180 | Values: 583.2 587.9 599.4 625.6 678.8 11835.2 12808.1 12821.8 12827.6 12860.9 12048.8 12125.5 12174.8 12190.3 12535.9 654.8 668.4 673.6 673.6 673.7 512.1 526.0 640.0 659.2 660.8 704.3 707.3 709.5 710.3 712.4 686.2 699.0 704.1 705.7 708.5 367.3 384.9 488.0 499.6 508.3 11935.1 11943.6 12469.9 12486.3 12486.4 11175.4 11621.2 11627.6 11917.6 12100.7 9319.2 9775.4 10471.0 10681.4 10980.1 3078.2 3087.8 3165.5 3217.1 3271.2 57965.0 63633.0 63972.7 64304.7 64326.0 9137.5 9329.9 9530.6 9648.4 9835.4 17444.7 17597.1 17642.8 17645.6 17679.3 6428.3 6467.5 6469.6 6487.4 6605.9 3323.2 3398.0 3522.6 3586.1 3610.7 2411.0 2502.7 2577.1 2579.5 2584.8 412.5 430.0 433.5 434.4 435.4 444.8 510.4 542.4 577.7 602.3 7254.8 7285.5 7467.7 7481.7 7520.0 535.2 547.3 553.6 555.7 615.7 3490.4 3505.4 3573.9 3594.9 3663.7 69740.0 72116.6 72366.3 81219.0 84399.0 74188.0 75666.2 75889.0 75989.0 76593.6 57901.4 60575.7 67421.0 68430.1 69073.3 7695.1 7832.7 8041.2 8052.4 8052.7 41491.8 42766.0 43086.8 43770.1 43826.6 53803.0 56919.0 59274.4 60085.4 60461.0 9671.5 9908.1 10226.3 10230.4 10502.6 11960.9 11991.5 12093.2 12195.5 12273.9 11292.9 11344.5 11387.4 11500.4 11587.2 10697.9 10814.5 10941.2 10996.3 11092.1 10507.1 10709.2 10789.9 10896.1 11051.7 9261.2 9921.3 10036.7 10171.1 10318.7 8917.6 8954.8 9103.8 9176.1 9422.6
09:31:16 INFO - PROCESS | 3180 | -------- Summary: end --------
09:31:16 INFO - PROCESS | 3180 |
09:31:17 INFO - TEST-INFO | 3180: exit 0
09:31:17 INFO - TEST-OK | dromaeo_css | took 1124170ms
09:31:17 INFO - TEST-START | kraken
09:31:17 INFO - operating with platform_type : w7_
09:31:17 INFO - Initialising browser for kraken test...
09:31:17 INFO - TEST-INFO | started process 3544 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpkkorsg\profile http://localhost:49718/getInfo.html)
09:31:22 INFO - PROCESS | 3544 | __metrics Screen width/height:1600/1200
09:31:22 INFO - PROCESS | 3544 | colorDepth:24
09:31:22 INFO - PROCESS | 3544 | Browser inner width/height: 1010/674
09:31:22 INFO - PROCESS | 3544 | __metrics
09:31:22 INFO - PROCESS | 3544 | 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:31:22 INFO - PROCESS | 3544 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
09:31:23 INFO - PROCESS | 3544 | console.error:
09:31:23 INFO - PROCESS | 3544 | Message: Error: Connection closed before committing the transaction.
09:31:23 INFO - PROCESS | 3544 | Stack:
09:31:23 INFO - PROCESS | 3544 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:937:23
09:31:23 INFO - PROCESS | 3544 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7
09:31:23 INFO - PROCESS | 3544 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11
09:31:23 INFO - PROCESS | 3544 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7
09:31:23 INFO - PROCESS | 3544 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7
09:31:23 INFO - PROCESS | 3544 | TaskImpl_run@resource://gre/modules/Task.jsm:324:13
09:31:23 INFO - PROCESS | 3544 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9
09:31:23 INFO - PROCESS | 3544 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15
09:31:23 INFO - PROCESS | 3544 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5
09:31:23 INFO - PROCESS | 3544 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29
09:31:23 INFO - PROCESS | 3544 | Barrier/this.client.addBlocker/promise13.2%
09:32:24 INFO - TinderboxPrint: I/O read bytes / time
71,541,248 / 878,690
09:32:24 INFO - TinderboxPrint: I/O write bytes / time
482,405,888 / 3,876,580
09:32:24 INFO - TinderboxPrint: CPU idle
8,295.1 (86.8%)
09:32:24 INFO - TinderboxPrint: CPU user
1,221.1 (12.8%)
09:32:24 INFO - install - Wall time: 3s; CPU: 8.0%; Read bytes: 0; Write bytes: 73334272; Read time: 0; Write time: 663340
09:32:24 INFO - run-tests - Wall time: 1192s; CPU: 13.0%; Read bytes: 71432704; Write bytes: 405128192; Read time: 863250; Write time: 3148790
09:32:24 INFO - Running post-run listener: _upload_blobber_files
09:32:24 INFO - Blob upload gear active.
09:32:24 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir.
09:32:24 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
09:32:24 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\venv\\Scripts\\blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', 'C:\\slave\\test\\oauth.txt', '-b', 'Autoland-Non-PGO', '-d', 'C:\\slave\\test\\build\\blobber_upload_dir', '--output-manifest', 'C:\\slave\\test\\build\\uploaded_files.json']
09:32:24 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\venv\Scripts\blobberc.py -u https://blobupload.elasticbeanstalk.com -a C:\slave\test\oauth.txt -b Autoland-Non-PGO -d C:\slave\test\build\blobber_upload_dir --output-manifest C:\slave\test\build\uploaded_files.json
09:32:25 INFO - (blobuploader) - INFO - Open directory for files ...
09:32:25 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log ...
09:32:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
09:32:25 INFO - (blobuploader) - INFO - Uploading, attempt #1.
09:32:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded
09:32:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
09:32:26 INFO - (blobuploader) - INFO - Done attempting.
09:32:26 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log ...
09:32:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
09:32:26 INFO - (blobuploader) - INFO - Uploading, attempt #1.
09:32:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded
09:32:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
09:32:27 INFO - (blobuploader) - INFO - Done attempting.
09:32:27 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ...
09:32:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
09:32:27 INFO - (blobuploader) - INFO - Uploading, attempt #1.
09:32:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
09:32:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
09:32:40 INFO - (blobuploader) - INFO - Done attempting.
09:32:40 INFO - (blobuploader) - INFO - Iteration through files over.
09:32:40 INFO - Return code: 0
09:32:40 INFO - rmtree: C:\slave\test\build\uploaded_files.json
09:32:40 INFO - Using _rmtree_windows ...
09:32:40 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1
09:32:40 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/351c79ab9e6ff185f0520f4cb409369ccfcd4ceea04aca4e519e06eecd2cdf40d29db60d8835f1abee13abf81cee5d2aa48c43caa5e16445770f6e969ab9ea16", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/27956ac2cc5a79c5a11891cbeca223ccb515fc1469ba59d40862f78043f5922eb35cb6bf61c3272dd742b2f5f680e5b2a2b2231680db571d6a2c706786a2943b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/f0b91f006bc7fa77ef823a3a3d2037c1fe5fdccbde74f23b02708eee2165388ec6acd5832ebf9d1a5af293102b58536560ae7880e8113d91d3730f7c758bc40d"}
09:32:40 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files
09:32:40 INFO - Writing to file C:\slave\test\properties\blobber_files
09:32:40 INFO - Contents:
09:32:40 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/351c79ab9e6ff185f0520f4cb409369ccfcd4ceea04aca4e519e06eecd2cdf40d29db60d8835f1abee13abf81cee5d2aa48c43caa5e16445770f6e969ab9ea16", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/27956ac2cc5a79c5a11891cbeca223ccb515fc1469ba59d40862f78043f5922eb35cb6bf61c3272dd742b2f5f680e5b2a2b2231680db571d6a2c706786a2943b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/f0b91f006bc7fa77ef823a3a3d2037c1fe5fdccbde74f23b02708eee2165388ec6acd5832ebf9d1a5af293102b58536560ae7880e8113d91d3730f7c758bc40d"}
09:32:40 INFO - Running post-run listener: copy_logs_to_upload_dir
09:32:40 INFO - Copying logs to upload dir...
09:32:40 INFO - mkdir: C:\slave\test\build\upload\logs
09:32:40 INFO - Copying logs to upload dir...
09:32:40 INFO - Using _rmtree_windows ...
09:32:40 INFO - Using _rmtree_windows ...
09:32:40 INFO - Using _rmtree_windows ...
09:32:40 INFO - Using _rmtree_windows ...
09:32:40 INFO - Using _rmtree_windows ...
09:32:40 INFO - Using _rmtree_windows ...
09:32:40 INFO - Using _rmtree_windows ...
program finished with exit code 0
elapsedTime=1357.489000
========= master_lag: 0.03 =========
========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 22 mins, 37 secs) (at 2016-09-28 09:32:40.595248) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-28 09:32:40.596193) =========
'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-142
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-142
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-142
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/351c79ab9e6ff185f0520f4cb409369ccfcd4ceea04aca4e519e06eecd2cdf40d29db60d8835f1abee13abf81cee5d2aa48c43caa5e16445770f6e969ab9ea16", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/27956ac2cc5a79c5a11891cbeca223ccb515fc1469ba59d40862f78043f5922eb35cb6bf61c3272dd742b2f5f680e5b2a2b2231680db571d6a2c706786a2943b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/f0b91f006bc7fa77ef823a3a3d2037c1fe5fdccbde74f23b02708eee2165388ec6acd5832ebf9d1a5af293102b58536560ae7880e8113d91d3730f7c758bc40d"}
build_url:https://queue.taskcluster.net/v1/task/fDJXzSd1Qeik6j77PXtRRg/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/fDJXzSd1Qeik6j77PXtRRg/artifacts/public/build/firefox-52.0a1.en-US.win32.zip'
blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/351c79ab9e6ff185f0520f4cb409369ccfcd4ceea04aca4e519e06eecd2cdf40d29db60d8835f1abee13abf81cee5d2aa48c43caa5e16445770f6e969ab9ea16", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/27956ac2cc5a79c5a11891cbeca223ccb515fc1469ba59d40862f78043f5922eb35cb6bf61c3272dd742b2f5f680e5b2a2b2231680db571d6a2c706786a2943b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/f0b91f006bc7fa77ef823a3a3d2037c1fe5fdccbde74f23b02708eee2165388ec6acd5832ebf9d1a5af293102b58536560ae7880e8113d91d3730f7c758bc40d"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-28 09:32:40.714827) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 09:32:40.715699) =========
'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-142
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-142
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-142
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
program finished with exit code 0
elapsedTime=0.101000
========= master_lag: 0.01 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 09:32:40.829955) =========
========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-09-28 09:32:40.830334) =========
========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-09-28 09:32:41.377102) =========
========= Total master_lag: 0.44 =========