builder: mozilla-central_win7_ix_test-dromaeojs
slave: t-w732-ix-044
starttime: 1479312829.86
results: success (0)
buildid: 20161116062433
builduid: 233db2d1b4034c7aabaec581ef4e7cc9
revision: 51750761f2c61c64cf0553f6cb5fefd4999d3bc0
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-16 08:13:49.856237) =========
master: http://buildbot-master119.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-16 08:13:49.856938) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-16 08:13:49.857364) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-16 08:13:49.971708) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-16 08:13:49.972119) =========
'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-044
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-044
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-044
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
--08:13:50-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
=> `archiver_client.py'
Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102
Connecting to hg.mozilla.org|63.245.215.25|:443... connected.
WARNING: Certificate verification error for hg.mozilla.org: certificate signature failure
HTTP request sent, awaiting response... 200 Script output follows
Length: 12,179 (12K) [text/x-python]
0K .......... . 100% 9.15 MB/s
08:13:51 (9.15 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=1.141000
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-16 08:13:51.136274) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 7 secs) (at 2016-11-16 08:13:51.136587) =========
'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-044
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-044
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-044
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=7.502000
========= master_lag: 0.02 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 7 secs) (at 2016-11-16 08:13:58.654538) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-16 08:13:58.655184) =========
'bash' '-c' u'python archiver_client.py mozharness --repo mozilla-central --rev 51750761f2c61c64cf0553f6cb5fefd4999d3bc0 --destination scripts --debug'
in dir C:\slave\test\. (timeout 1200 secs)
watching logfiles {}
argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 51750761f2c61c64cf0553f6cb5fefd4999d3bc0 --destination scripts --debug']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-044
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-044
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-044
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-11-16 08:13:58,993 truncating revision to first 12 chars
2016-11-16 08:13:58,993 Setting DEBUG logging.
2016-11-16 08:13:58,993 attempt 1/10
2016-11-16 08:13:58,993 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/51750761f2c6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2016-11-16 08:13:59,831 unpacking tar archive at: mozilla-central-51750761f2c6/testing/mozharness/
2016-11-16 08:13:59,960 skipping symlink on windows: configs/single_locale/linux32.py
program finished with exit code 0
elapsedTime=1.605000
========= master_lag: 0.05 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-16 08:14:00.305796) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-16 08:14:00.306270) =========
script_repo_revision: 51750761f2c61c64cf0553f6cb5fefd4999d3bc0
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-16 08:14:00.306905) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-16 08:14:00.307362) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-16 08:14:00.340313) =========
========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 22 mins, 1 secs) (at 2016-11-16 08:14:00.340945) =========
'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Firefox-Non-PGO' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Firefox-Non-PGO'
in dir C:\slave\test\. (timeout 3600 secs) (maxTime 7200 secs)
watching logfiles {}
argv: ['c:/mozilla-build/python27/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-044
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-044
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-044
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
08:14:00 INFO - MultiFileLogger online at 20161116 08:14:00 in C:\slave\test
08:14:00 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO
08:14:00 INFO - Dumping config to C:\slave\test\logs\localconfig.json.
08:14:00 INFO - {'append_to_log': False,
08:14:00 INFO - 'base_work_dir': 'C:\\slave\\test',
08:14:00 INFO - 'blob_upload_branch': 'Firefox-Non-PGO',
08:14:00 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt',
08:14:00 INFO - 'branch': 'Firefox-Non-PGO',
08:14:00 INFO - 'buildbot_json_path': 'buildprops.json',
08:14:00 INFO - 'config_files': ('talos/windows_config.py',),
08:14:00 INFO - 'default_actions': ('clobber',
08:14:00 INFO - 'read-buildbot-config',
08:14:00 INFO - 'download-and-extract',
08:14:00 INFO - 'populate-webroot',
08:14:00 INFO - 'create-virtualenv',
08:14:00 INFO - 'install',
08:14:00 INFO - 'run-tests'),
08:14:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
08:14:00 INFO - 'download_minidump_stackwalk': True,
08:14:00 INFO - 'download_symbols': 'ondemand',
08:14:00 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python',
08:14:00 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'),
08:14:00 INFO - 'hg': 'c:/mozilla-build/hg/hg',
08:14:00 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python',
08:14:00 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'),
08:14:00 INFO - 'python': 'c:/mozilla-build/python27/python.exe',
08:14:00 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe',
08:14:00 INFO - 'C:/mozilla-build/tooltool.py'),
08:14:00 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe',
08:14:00 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')},
08:14:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
08:14:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
08:14:00 INFO - 'installer_path': 'installer.exe',
08:14:00 INFO - 'log_level': 'info',
08:14:00 INFO - 'log_name': 'talos',
08:14:00 INFO - 'log_to_console': True,
08:14:00 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe',
08:14:00 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe',
08:14:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest',
08:14:00 INFO - 'opt_config_files': (),
08:14:00 INFO - 'pip_index': False,
08:14:00 INFO - 'sps_profile': False,
08:14:00 INFO - 'sps_profile_interval': 0,
08:14:00 INFO - 'suite': 'dromaeojs',
08:14:00 INFO - 'system_bits': '32',
08:14:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
08:14:00 INFO - 'title': 'T-W732-IX-044',
08:14:00 INFO - 'use_talos_json': True,
08:14:00 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'),
08:14:00 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv',
08:14:00 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll',
08:14:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
08:14:00 INFO - 'work_dir': 'build'}
08:14:00 INFO - [mozharness: 2016-11-16 16:14:00.828000Z] Running clobber step.
08:14:00 INFO - Running pre-action listener: _resource_record_pre_action
08:14:00 INFO - Running main action method: clobber
08:14:00 INFO - rmtree: C:\slave\test\build
08:14:00 INFO - Using _rmtree_windows ...
08:14:00 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1
08:14:00 INFO - Running post-action listener: _resource_record_post_action
08:14:00 INFO - [mozharness: 2016-11-16 16:14:00.914000Z] Finished clobber step (success)
08:14:00 INFO - [mozharness: 2016-11-16 16:14:00.914000Z] Running read-buildbot-config step.
08:14:00 INFO - Running pre-action listener: _resource_record_pre_action
08:14:00 INFO - Running main action method: read_buildbot_config
08:14:00 INFO - Using buildbot properties:
08:14:00 INFO - {
08:14:00 INFO - "project": "",
08:14:00 INFO - "product": "firefox",
08:14:00 INFO - "script_repo_revision": "production",
08:14:00 INFO - "builddir": "mozilla-central_win7_ix_test-dromaeojs",
08:14:00 INFO - "repository": "",
08:14:00 INFO - "buildername": "Windows 7 32-bit mozilla-central talos dromaeojs",
08:14:00 INFO - "buildid": "20161116062433",
08:14:00 INFO - "pgo_build": "False",
08:14:00 INFO - "basedir": "C:\\slave\\test",
08:14:00 INFO - "buildnumber": 24,
08:14:00 INFO - "slavename": "t-w732-ix-044",
08:14:00 INFO - "master": "http://buildbot-master119.bb.releng.scl3.mozilla.com:8201/",
08:14:00 INFO - "platform": "win7_ix",
08:14:00 INFO - "scheduler": "tests-mozilla-central-win32-talos",
08:14:00 INFO - "branch": "mozilla-central",
08:14:00 INFO - "repo_path": "mozilla-central",
08:14:00 INFO - "revision": "51750761f2c61c64cf0553f6cb5fefd4999d3bc0",
08:14:00 INFO - "stage_platform": "win32",
08:14:00 INFO - "builduid": "233db2d1b4034c7aabaec581ef4e7cc9",
08:14:00 INFO - "slavebuilddir": "test"
08:14:00 INFO - }
08:14:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/VgybdUy1R_aArtn-RBlm7w/artifacts/public/build/firefox-53.0a1.en-US.win32.zip.
08:14:00 INFO - Running post-action listener: _resource_record_post_action
08:14:00 INFO - [mozharness: 2016-11-16 16:14:00.918000Z] Finished read-buildbot-config step (success)
08:14:00 INFO - [mozharness: 2016-11-16 16:14:00.918000Z] Running download-and-extract step.
08:14:00 INFO - Running pre-action listener: _resource_record_pre_action
08:14:00 INFO - Running main action method: download_and_extract
08:14:00 INFO - mkdir: C:\slave\test\build\tests
08:14:00 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')]}
08:14:00 INFO - trying https://queue.taskcluster.net/v1/task/VgybdUy1R_aArtn-RBlm7w/artifacts/public/build/firefox-53.0a1.en-US.win32.test_packages.json
08:14:00 INFO - Downloading https://queue.taskcluster.net/v1/task/VgybdUy1R_aArtn-RBlm7w/artifacts/public/build/firefox-53.0a1.en-US.win32.test_packages.json to C:\slave\test\build\firefox-53.0a1.en-US.win32.test_packages.json
08:14:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VgybdUy1R_aArtn-RBlm7w/artifacts/public/build/firefox-53.0a1.en-US.win32.test_packages.json', 'file_name': 'C:\\slave\\test\\build\\firefox-53.0a1.en-US.win32.test_packages.json'}, attempt #1
08:14:01 INFO - Downloaded 1274 bytes.
08:14:01 INFO - Reading from file C:\slave\test\build\firefox-53.0a1.en-US.win32.test_packages.json
08:14:01 INFO - Using the following test package requirements:
08:14:01 INFO - {u'common': [u'firefox-53.0a1.en-US.win32.common.tests.zip'],
08:14:01 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.win32.common.tests.zip',
08:14:01 INFO - u'firefox-53.0a1.en-US.win32.cppunittest.tests.zip'],
08:14:01 INFO - u'gtest': [u'firefox-53.0a1.en-US.win32.common.tests.zip',
08:14:01 INFO - u'firefox-53.0a1.en-US.win32.gtest.tests.zip'],
08:14:01 INFO - u'jittest': [u'firefox-53.0a1.en-US.win32.common.tests.zip',
08:14:01 INFO - u'jsshell-win32.zip'],
08:14:01 INFO - u'mochitest': [u'firefox-53.0a1.en-US.win32.common.tests.zip',
08:14:01 INFO - u'firefox-53.0a1.en-US.win32.mochitest.tests.zip'],
08:14:01 INFO - u'mozbase': [u'firefox-53.0a1.en-US.win32.common.tests.zip'],
08:14:01 INFO - u'reftest': [u'firefox-53.0a1.en-US.win32.common.tests.zip',
08:14:01 INFO - u'firefox-53.0a1.en-US.win32.reftest.tests.zip'],
08:14:01 INFO - u'talos': [u'firefox-53.0a1.en-US.win32.common.tests.zip',
08:14:01 INFO - u'firefox-53.0a1.en-US.win32.talos.tests.zip'],
08:14:01 INFO - u'web-platform': [u'firefox-53.0a1.en-US.win32.common.tests.zip',
08:14:01 INFO - u'firefox-53.0a1.en-US.win32.web-platform.tests.zip'],
08:14:01 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.win32.common.tests.zip',
08:14:01 INFO - u'firefox-53.0a1.en-US.win32.xpcshell.tests.zip']}
08:14:01 INFO - Downloading packages: [u'firefox-53.0a1.en-US.win32.common.tests.zip'] for test suite category: common
08:14:01 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/VgybdUy1R_aArtn-RBlm7w/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip
08:14:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VgybdUy1R_aArtn-RBlm7w/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip'}, attempt #1
08:14:01 INFO - Fetch https://queue.taskcluster.net/v1/task/VgybdUy1R_aArtn-RBlm7w/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip into memory
08:14:02 INFO - Http code: 200
08:14:02 INFO - via: 1.1 ff09df097f823b2834408d17e9779d62.cloudfront.net (CloudFront)
08:14:02 INFO - x-amz-cf-id: xy5CcwFT8-RdVaN6ywb39cGfVwkJYm90kas6wkLy-b-bwce3xijucA==
08:14:02 INFO - x-amz-version-id: uDEOitiD52Bzoo3kzPRW9WwvJjx_QMIt
08:14:03 INFO - Expected file size: 17025650
08:14:03 INFO - Obtained file size: 17025650
08:14:33 INFO - Downloading packages: [u'firefox-53.0a1.en-US.win32.common.tests.zip', u'firefox-53.0a1.en-US.win32.talos.tests.zip'] for test suite category: talos
08:14:33 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/VgybdUy1R_aArtn-RBlm7w/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip
08:14:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VgybdUy1R_aArtn-RBlm7w/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip'}, attempt #1
08:14:33 INFO - Fetch https://queue.taskcluster.net/v1/task/VgybdUy1R_aArtn-RBlm7w/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip into memory
08:14:33 INFO - Http code: 200
08:14:33 INFO - via: 1.1 95da1452a75435200220a7075ca3893f.cloudfront.net (CloudFront)
08:14:33 INFO - x-amz-cf-id: Gg6hnAi5vS1JWZWQo2XQExFmADjJ446E_0i9_dsLKPqNxJQB8WGnLA==
08:14:33 INFO - x-amz-version-id: uDEOitiD52Bzoo3kzPRW9WwvJjx_QMIt
08:14:34 INFO - Expected file size: 17025650
08:14:34 INFO - Obtained file size: 17025650
08:14:42 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/VgybdUy1R_aArtn-RBlm7w/artifacts/public/build/firefox-53.0a1.en-US.win32.talos.tests.zip
08:14:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VgybdUy1R_aArtn-RBlm7w/artifacts/public/build/firefox-53.0a1.en-US.win32.talos.tests.zip'}, attempt #1
08:14:42 INFO - Fetch https://queue.taskcluster.net/v1/task/VgybdUy1R_aArtn-RBlm7w/artifacts/public/build/firefox-53.0a1.en-US.win32.talos.tests.zip into memory
08:14:42 INFO - Http code: 200
08:14:42 INFO - via: 1.1 6e65abb04cb818a6ec78111935b507f7.cloudfront.net (CloudFront)
08:14:42 INFO - x-amz-cf-id: tzsYSP5tzAxdkb-YRTe9uPbtTlji2iklKtZoHr7Tw6fErTfJQvmxGg==
08:14:42 INFO - x-amz-version-id: NTvHjeqt9zq8.9R6RASWg97_tLXjCMSA
08:14:43 INFO - Expected file size: 14409366
08:14:43 INFO - Obtained file size: 14409366
08:14:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
08:14:47 INFO - trying https://queue.taskcluster.net/v1/task/VgybdUy1R_aArtn-RBlm7w/artifacts/public/build/firefox-53.0a1.en-US.win32.zip
08:14:47 INFO - Downloading https://queue.taskcluster.net/v1/task/VgybdUy1R_aArtn-RBlm7w/artifacts/public/build/firefox-53.0a1.en-US.win32.zip to C:\slave\test\build\firefox-53.0a1.en-US.win32.zip
08:14:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VgybdUy1R_aArtn-RBlm7w/artifacts/public/build/firefox-53.0a1.en-US.win32.zip', 'file_name': 'C:\\slave\\test\\build\\firefox-53.0a1.en-US.win32.zip'}, attempt #1
08:14:50 INFO - Downloaded 53280393 bytes.
08:14:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VgybdUy1R_aArtn-RBlm7w/artifacts/public/build/firefox-53.0a1.en-US.win32.zip
08:14:50 INFO - mkdir: C:\slave\test\properties
08:14:50 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url
08:14:50 INFO - Writing to file C:\slave\test\properties\build_url
08:14:50 INFO - Contents:
08:14:50 INFO - build_url:https://queue.taskcluster.net/v1/task/VgybdUy1R_aArtn-RBlm7w/artifacts/public/build/firefox-53.0a1.en-US.win32.zip
08:14:51 INFO - Running post-action listener: _resource_record_post_action
08:14:51 INFO - Running post-action listener: set_extra_try_arguments
08:14:51 INFO - [mozharness: 2016-11-16 16:14:51.326000Z] Finished download-and-extract step (success)
08:14:51 INFO - [mozharness: 2016-11-16 16:14:51.326000Z] Running populate-webroot step.
08:14:51 INFO - Running pre-action listener: _resource_record_pre_action
08:14:51 INFO - Running main action method: populate_webroot
08:14:51 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']},
08:14:51 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']},
08:14:51 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome',
08:14:51 INFO - u'--tppagecycles',
08:14:51 INFO - u'7'],
08:14:51 INFO - u'tests': [u'tsvgm']}},
08:14:51 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'],
08:14:51 INFO - u'tests': [u'tresize', u'tcanvasmark']},
08:14:51 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']},
08:14:51 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'],
08:14:51 INFO - u'tests': [u'dromaeo_css', u'kraken']},
08:14:51 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']},
08:14:51 INFO - u'g1': {u'pagesets_name': u'tp5n.zip',
08:14:51 INFO - u'talos_options': [u'--disable-e10s'],
08:14:51 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
08:14:51 INFO - u'g1-e10s': {u'pagesets_name': u'tp5n.zip',
08:14:51 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
08:14:51 INFO - u'g2': {u'pagesets_name': u'tp5n.zip',
08:14:51 INFO - u'talos_options': [u'--disable-e10s'],
08:14:51 INFO - u'tests': [u'damp', u'tps']},
08:14:51 INFO - u'g2-e10s': {u'pagesets_name': u'tp5n.zip',
08:14:51 INFO - u'tests': [u'damp', u'tps']},
08:14:51 INFO - u'g3': {u'talos_options': [u'--disable-e10s'],
08:14:51 INFO - u'tests': [u'dromaeo_dom']},
08:14:51 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']},
08:14:51 INFO - u'g4': {u'talos_options': [u'--disable-e10s'],
08:14:51 INFO - u'tests': [u'basic_compositor_video']},
08:14:51 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']},
08:14:51 INFO - u'other': {u'talos_options': [u'--disable-e10s'],
08:14:51 INFO - u'tests': [u'a11yr',
08:14:51 INFO - u'ts_paint',
08:14:51 INFO - u'tpaint',
08:14:51 INFO - u'sessionrestore',
08:14:51 INFO - u'sessionrestore_no_auto_restore',
08:14:51 INFO - u'tabpaint']},
08:14:51 INFO - u'other-e10s': {u'tests': [u'a11yr',
08:14:51 INFO - u'ts_paint',
08:14:51 INFO - u'tpaint',
08:14:51 INFO - u'sessionrestore',
08:14:51 INFO - u'sessionrestore_no_auto_restore',
08:14:51 INFO - u'tabpaint']},
08:14:51 INFO - u'other-e10s_l64': {u'tests': [u'a11yr',
08:14:51 INFO - u'ts_paint',
08:14:51 INFO - u'tpaint',
08:14:51 INFO - u'sessionrestore',
08:14:51 INFO - u'sessionrestore_no_auto_restore',
08:14:51 INFO - u'tabpaint']},
08:14:51 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr',
08:14:51 INFO - u'ts_paint',
08:14:51 INFO - u'tpaint',
08:14:51 INFO - u'sessionrestore',
08:14:51 INFO - u'sessionrestore_no_auto_restore',
08:14:51 INFO - u'tabpaint']},
08:14:51 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'],
08:14:51 INFO - u'tests': [u'a11yr',
08:14:51 INFO - u'ts_paint',
08:14:51 INFO - u'tpaint',
08:14:51 INFO - u'sessionrestore',
08:14:51 INFO - u'sessionrestore_no_auto_restore',
08:14:51 INFO - u'tabpaint']},
08:14:51 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'],
08:14:51 INFO - u'tests': [u'a11yr',
08:14:51 INFO - u'ts_paint',
08:14:51 INFO - u'tpaint',
08:14:51 INFO - u'sessionrestore',
08:14:51 INFO - u'sessionrestore_no_auto_restore',
08:14:51 INFO - u'tabpaint']},
08:14:51 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'],
08:14:51 INFO - u'tests': [u'tsvgx',
08:14:51 INFO - u'tsvgr_opacity',
08:14:51 INFO - u'tart',
08:14:51 INFO - u'tscrollx',
08:14:51 INFO - u'cart']},
08:14:51 INFO - u'svgr-e10s': {u'tests': [u'tsvgx',
08:14:51 INFO - u'tsvgr_opacity',
08:14:51 INFO - u'tart',
08:14:51 INFO - u'tscrollx',
08:14:51 INFO - u'cart']},
08:14:51 INFO - u'tp5o': {u'pagesets_name': u'tp5n.zip',
08:14:51 INFO - u'talos_options': [u'--disable-e10s'],
08:14:51 INFO - u'tests': [u'tp5o']},
08:14:51 INFO - u'tp5o-e10s': {u'pagesets_name': u'tp5n.zip',
08:14:51 INFO - u'tests': [u'tp5o']},
08:14:51 INFO - u'xperf': {u'pagesets_name': u'tp5n.zip',
08:14:51 INFO - u'talos_options': [u'--disable-e10s',
08:14:51 INFO - u'--xperf_path',
08:14:51 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
08:14:51 INFO - u'tests': [u'tp5n']},
08:14:51 INFO - u'xperf-e10s': {u'pagesets_name': u'tp5n.zip',
08:14:51 INFO - u'talos_options': [u'--xperf_path',
08:14:51 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
08:14:51 INFO - u'tests': [u'tp5n']}},
08:14:51 INFO - u'talos.zip': {u'path': u'',
08:14:51 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}}
08:14:51 INFO - Running post-action listener: _resource_record_post_action
08:14:51 INFO - [mozharness: 2016-11-16 16:14:51.351000Z] Finished populate-webroot step (success)
08:14:51 INFO - [mozharness: 2016-11-16 16:14:51.351000Z] Running create-virtualenv step.
08:14:51 INFO - Running pre-action listener: _resource_record_pre_action
08:14:51 INFO - Running main action method: create_virtualenv
08:14:51 INFO - Creating virtualenv C:\slave\test\build/venv
08:14:51 INFO - mkdir: C:\slave\test\build\venv\Scripts
08:14:51 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll
08:14:51 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'!
08:14:51 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
08:14:51 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv
08:15:01 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe
08:15:05 INFO - Installing distribute......................................................................................................................................................................................done.
08:15:07 INFO - Return code: 0
08:15:07 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version']
08:15:07 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version
08:15:07 INFO - Reading from file tmpfile_stdout
08:15:07 INFO - Output received:
08:15:07 INFO - pip 0.8.2 from c:\slave\test\build\venv\lib\site-packages\pip-0.8.2-py2.7.egg (python 2.7)
08:15:07 INFO - Using _rmtree_windows ...
08:15:07 INFO - Using _rmtree_windows ...
08:15:07 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv
08:15:07 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')]}
08:15:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
08:15:07 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')]}
08:15:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
08:15:07 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 0x01890640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01F4F020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0183B0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01943E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F315C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-044', 'USERDOMAIN': 'T-W732-IX-044', '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-044', '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
08:15:07 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
08:15:07 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
08:15:07 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
08:15:07 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
08:15:07 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
08:15:07 INFO - 'COMPUTERNAME': 'T-W732-IX-044',
08:15:07 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
08:15:07 INFO - 'CYGWINBASE': 'C:\\cygwin',
08:15:07 INFO - 'DCLOCATION': 'SCL3',
08:15:07 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
08:15:07 INFO - 'FP_NO_HOST_CHECK': 'NO',
08:15:07 INFO - 'HOMEDRIVE': 'C:',
08:15:07 INFO - 'HOMEPATH': '\\Users\\cltbld',
08:15:07 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
08:15:07 INFO - 'KTS_VERSION': '1.19c',
08:15:07 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
08:15:07 INFO - 'LOGONSERVER': '\\\\T-W732-IX-044',
08:15:07 INFO - 'MONDIR': 'C:\\Monitor_config\\',
08:15:07 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
08:15:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:15:07 INFO - 'MOZ_NO_REMOTE': '1',
08:15:07 INFO - 'NO_EM_RESTART': '1',
08:15:07 INFO - 'NUMBER_OF_PROCESSORS': '8',
08:15:07 INFO - 'OS': 'Windows_NT',
08:15:07 INFO - 'OURDRIVE': 'C:',
08:15:07 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;',
08:15:07 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
08:15:07 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
08:15:07 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
08:15:07 INFO - 'PROCESSOR_LEVEL': '6',
08:15:07 INFO - 'PROCESSOR_REVISION': '1e05',
08:15:07 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
08:15:07 INFO - 'PROGRAMFILES': 'C:\\Program Files',
08:15:07 INFO - 'PROMPT': '$P$G',
08:15:07 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
08:15:07 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
08:15:07 INFO - 'PUBLIC': 'C:\\Users\\Public',
08:15:07 INFO - 'PWD': 'C:\\slave\\test',
08:15:07 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
08:15:07 INFO - 'SLAVEDIR': 'C:\\slave\\',
08:15:07 INFO - 'SYSTEMDRIVE': 'C:',
08:15:07 INFO - 'SYSTEMROOT': 'C:\\windows',
08:15:07 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
08:15:07 INFO - 'TEST1': 'testie',
08:15:07 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
08:15:07 INFO - 'USERDOMAIN': 'T-W732-IX-044',
08:15:07 INFO - 'USERNAME': 'cltbld',
08:15:07 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
08:15:07 INFO - 'WINDIR': 'C:\\windows',
08:15:07 INFO - 'WINDOWS_TRACING_FLAGS': '3',
08:15:07 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
08:15:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
08:15:08 INFO - Ignoring indexes: http://pypi.python.org/simple/
08:15:08 INFO - Downloading/unpacking pip>=1.5
08:15:12 INFO - Running setup.py egg_info for package pip
08:15:12 INFO - warning: no files found matching 'pip\cacert.pem'
08:15:12 INFO - warning: no files found matching '*.html' under directory 'docs'
08:15:12 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
08:15:12 INFO - no previously-included directories found matching 'docs\_build\_sources'
08:15:12 INFO - Installing collected packages: pip
08:15:12 INFO - Found existing installation: pip 0.8.2
08:15:12 INFO - Uninstalling pip:
08:15:12 INFO - Successfully uninstalled pip
08:15:12 INFO - Running setup.py install for pip
08:15:12 INFO - warning: no files found matching 'pip\cacert.pem'
08:15:12 INFO - warning: no files found matching '*.html' under directory 'docs'
08:15:12 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
08:15:12 INFO - no previously-included directories found matching 'docs\_build\_sources'
08:15:12 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts
08:15:12 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts
08:15:12 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts
08:15:12 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts
08:15:12 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts
08:15:12 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts
08:15:12 INFO - Successfully installed pip
08:15:12 INFO - Cleaning up...
08:15:12 INFO - Return code: 0
08:15:12 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv
08:15:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
08:15:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
08:15:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
08:15:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
08:15:12 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x01890640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01F4F020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0183B0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01943E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F315C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-044', 'USERDOMAIN': 'T-W732-IX-044', '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-044', '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
08:15:12 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in C:\slave\test\build
08:15:12 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1
08:15:12 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
08:15:12 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
08:15:12 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
08:15:12 INFO - 'COMPUTERNAME': 'T-W732-IX-044',
08:15:12 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
08:15:12 INFO - 'CYGWINBASE': 'C:\\cygwin',
08:15:12 INFO - 'DCLOCATION': 'SCL3',
08:15:12 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
08:15:12 INFO - 'FP_NO_HOST_CHECK': 'NO',
08:15:12 INFO - 'HOMEDRIVE': 'C:',
08:15:12 INFO - 'HOMEPATH': '\\Users\\cltbld',
08:15:12 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
08:15:12 INFO - 'KTS_VERSION': '1.19c',
08:15:12 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
08:15:12 INFO - 'LOGONSERVER': '\\\\T-W732-IX-044',
08:15:12 INFO - 'MONDIR': 'C:\\Monitor_config\\',
08:15:12 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
08:15:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:15:12 INFO - 'MOZ_NO_REMOTE': '1',
08:15:12 INFO - 'NO_EM_RESTART': '1',
08:15:12 INFO - 'NUMBER_OF_PROCESSORS': '8',
08:15:12 INFO - 'OS': 'Windows_NT',
08:15:12 INFO - 'OURDRIVE': 'C:',
08:15:12 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
08:15:12 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
08:15:12 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
08:15:12 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
08:15:12 INFO - 'PROCESSOR_LEVEL': '6',
08:15:12 INFO - 'PROCESSOR_REVISION': '1e05',
08:15:12 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
08:15:12 INFO - 'PROGRAMFILES': 'C:\\Program Files',
08:15:12 INFO - 'PROMPT': '$P$G',
08:15:12 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
08:15:12 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
08:15:12 INFO - 'PUBLIC': 'C:\\Users\\Public',
08:15:12 INFO - 'PWD': 'C:\\slave\\test',
08:15:12 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
08:15:12 INFO - 'SLAVEDIR': 'C:\\slave\\',
08:15:12 INFO - 'SYSTEMDRIVE': 'C:',
08:15:12 INFO - 'SYSTEMROOT': 'C:\\windows',
08:15:12 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
08:15:12 INFO - 'TEST1': 'testie',
08:15:12 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
08:15:12 INFO - 'USERDOMAIN': 'T-W732-IX-044',
08:15:12 INFO - 'USERNAME': 'cltbld',
08:15:12 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
08:15:12 INFO - 'WINDIR': 'C:\\windows',
08:15:12 INFO - 'WINDOWS_TRACING_FLAGS': '3',
08:15:12 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
08:15:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
08:15:14 INFO - Ignoring indexes: https://pypi.python.org/simple/
08:15:14 INFO - Downloading/unpacking psutil>=3.1.1
08:15:14 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
08:15:14 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
08:15:18 INFO - Installing collected packages: psutil
08:15:18 INFO - Successfully installed psutil
08:15:18 INFO - Cleaning up...
08:15:18 INFO - Return code: 0
08:15:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv
08:15:18 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')]}
08:15:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
08:15:18 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')]}
08:15:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
08:15:18 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 0x01890640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01F4F020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0183B0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01943E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F315C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-044', 'USERDOMAIN': 'T-W732-IX-044', '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-044', '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
08:15:18 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
08:15:18 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
08:15:18 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
08:15:18 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
08:15:18 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
08:15:18 INFO - 'COMPUTERNAME': 'T-W732-IX-044',
08:15:18 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
08:15:18 INFO - 'CYGWINBASE': 'C:\\cygwin',
08:15:18 INFO - 'DCLOCATION': 'SCL3',
08:15:18 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
08:15:18 INFO - 'FP_NO_HOST_CHECK': 'NO',
08:15:18 INFO - 'HOMEDRIVE': 'C:',
08:15:18 INFO - 'HOMEPATH': '\\Users\\cltbld',
08:15:18 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
08:15:18 INFO - 'KTS_VERSION': '1.19c',
08:15:18 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
08:15:18 INFO - 'LOGONSERVER': '\\\\T-W732-IX-044',
08:15:18 INFO - 'MONDIR': 'C:\\Monitor_config\\',
08:15:18 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
08:15:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:15:18 INFO - 'MOZ_NO_REMOTE': '1',
08:15:18 INFO - 'NO_EM_RESTART': '1',
08:15:18 INFO - 'NUMBER_OF_PROCESSORS': '8',
08:15:18 INFO - 'OS': 'Windows_NT',
08:15:18 INFO - 'OURDRIVE': 'C:',
08:15:18 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;',
08:15:18 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
08:15:18 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
08:15:18 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
08:15:18 INFO - 'PROCESSOR_LEVEL': '6',
08:15:18 INFO - 'PROCESSOR_REVISION': '1e05',
08:15:18 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
08:15:18 INFO - 'PROGRAMFILES': 'C:\\Program Files',
08:15:18 INFO - 'PROMPT': '$P$G',
08:15:18 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
08:15:18 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
08:15:18 INFO - 'PUBLIC': 'C:\\Users\\Public',
08:15:18 INFO - 'PWD': 'C:\\slave\\test',
08:15:18 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
08:15:18 INFO - 'SLAVEDIR': 'C:\\slave\\',
08:15:18 INFO - 'SYSTEMDRIVE': 'C:',
08:15:18 INFO - 'SYSTEMROOT': 'C:\\windows',
08:15:18 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
08:15:18 INFO - 'TEST1': 'testie',
08:15:18 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
08:15:18 INFO - 'USERDOMAIN': 'T-W732-IX-044',
08:15:18 INFO - 'USERNAME': 'cltbld',
08:15:18 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
08:15:18 INFO - 'WINDIR': 'C:\\windows',
08:15:18 INFO - 'WINDOWS_TRACING_FLAGS': '3',
08:15:18 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
08:15:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
08:15:19 INFO - Ignoring indexes: https://pypi.python.org/simple/
08:15:19 INFO - Downloading/unpacking mozsystemmonitor==0.3
08:15:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
08:15:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
08:15:20 INFO - Downloading mozsystemmonitor-0.3.tar.gz
08:15:20 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor
08:15:20 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)
08:15:20 INFO - Installing collected packages: mozsystemmonitor
08:15:20 INFO - Running setup.py install for mozsystemmonitor
08:15:20 INFO - Successfully installed mozsystemmonitor
08:15:20 INFO - Cleaning up...
08:15:20 INFO - Return code: 0
08:15:20 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv
08:15:20 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')]}
08:15:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
08:15:20 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')]}
08:15:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
08:15:20 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 0x01890640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01F4F020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0183B0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01943E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F315C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-044', 'USERDOMAIN': 'T-W732-IX-044', '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-044', '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
08:15:21 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
08:15:21 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
08:15:21 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
08:15:21 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
08:15:21 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
08:15:21 INFO - 'COMPUTERNAME': 'T-W732-IX-044',
08:15:21 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
08:15:21 INFO - 'CYGWINBASE': 'C:\\cygwin',
08:15:21 INFO - 'DCLOCATION': 'SCL3',
08:15:21 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
08:15:21 INFO - 'FP_NO_HOST_CHECK': 'NO',
08:15:21 INFO - 'HOMEDRIVE': 'C:',
08:15:21 INFO - 'HOMEPATH': '\\Users\\cltbld',
08:15:21 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
08:15:21 INFO - 'KTS_VERSION': '1.19c',
08:15:21 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
08:15:21 INFO - 'LOGONSERVER': '\\\\T-W732-IX-044',
08:15:21 INFO - 'MONDIR': 'C:\\Monitor_config\\',
08:15:21 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
08:15:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:15:21 INFO - 'MOZ_NO_REMOTE': '1',
08:15:21 INFO - 'NO_EM_RESTART': '1',
08:15:21 INFO - 'NUMBER_OF_PROCESSORS': '8',
08:15:21 INFO - 'OS': 'Windows_NT',
08:15:21 INFO - 'OURDRIVE': 'C:',
08:15:21 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
08:15:21 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
08:15:21 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
08:15:21 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
08:15:21 INFO - 'PROCESSOR_LEVEL': '6',
08:15:21 INFO - 'PROCESSOR_REVISION': '1e05',
08:15:21 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
08:15:21 INFO - 'PROGRAMFILES': 'C:\\Program Files',
08:15:21 INFO - 'PROMPT': '$P$G',
08:15:21 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
08:15:21 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
08:15:21 INFO - 'PUBLIC': 'C:\\Users\\Public',
08:15:21 INFO - 'PWD': 'C:\\slave\\test',
08:15:21 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
08:15:21 INFO - 'SLAVEDIR': 'C:\\slave\\',
08:15:21 INFO - 'SYSTEMDRIVE': 'C:',
08:15:21 INFO - 'SYSTEMROOT': 'C:\\windows',
08:15:21 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
08:15:21 INFO - 'TEST1': 'testie',
08:15:21 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
08:15:21 INFO - 'USERDOMAIN': 'T-W732-IX-044',
08:15:21 INFO - 'USERNAME': 'cltbld',
08:15:21 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
08:15:21 INFO - 'WINDIR': 'C:\\windows',
08:15:21 INFO - 'WINDOWS_TRACING_FLAGS': '3',
08:15:21 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
08:15:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
08:15:21 INFO - Ignoring indexes: https://pypi.python.org/simple/
08:15:21 INFO - Downloading/unpacking jsonschema==2.5.1
08:15:21 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
08:15:21 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
08:15:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl
08:15:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1)
08:15: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
08:15: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
08:15:22 INFO - Downloading functools32-3.2.3-2.tar.gz
08:15:22 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32
08:15:23 INFO - warning: no files found matching '*.txt'
08:15:23 INFO - no previously-included directories found matching 'build'
08:15:23 INFO - no previously-included directories found matching 'dist'
08:15:23 INFO - no previously-included directories found matching '.git*'
08:15:23 INFO - Installing collected packages: jsonschema, functools32
08:15:23 INFO - Running setup.py install for functools32
08:15:23 INFO - warning: no files found matching '*.txt'
08:15:23 INFO - no previously-included directories found matching 'build'
08:15:23 INFO - no previously-included directories found matching 'dist'
08:15:23 INFO - no previously-included directories found matching '.git*'
08:15:23 INFO - Successfully installed jsonschema functools32
08:15:23 INFO - Cleaning up...
08:15:23 INFO - Return code: 0
08:15:23 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv
08:15: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')]}
08:15:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
08:15: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')]}
08:15:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
08:15:23 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x01890640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01F4F020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0183B0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01943E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F315C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-044', 'USERDOMAIN': 'T-W732-IX-044', '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-044', '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
08:15:23 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in C:\slave\test\build
08:15:23 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2
08:15:23 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
08:15:23 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
08:15:23 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
08:15:23 INFO - 'COMPUTERNAME': 'T-W732-IX-044',
08:15:23 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
08:15:23 INFO - 'CYGWINBASE': 'C:\\cygwin',
08:15:23 INFO - 'DCLOCATION': 'SCL3',
08:15:23 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
08:15:23 INFO - 'FP_NO_HOST_CHECK': 'NO',
08:15:23 INFO - 'HOMEDRIVE': 'C:',
08:15:23 INFO - 'HOMEPATH': '\\Users\\cltbld',
08:15:23 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
08:15:23 INFO - 'KTS_VERSION': '1.19c',
08:15:23 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
08:15:23 INFO - 'LOGONSERVER': '\\\\T-W732-IX-044',
08:15:23 INFO - 'MONDIR': 'C:\\Monitor_config\\',
08:15:23 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
08:15:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:15:23 INFO - 'MOZ_NO_REMOTE': '1',
08:15:23 INFO - 'NO_EM_RESTART': '1',
08:15:23 INFO - 'NUMBER_OF_PROCESSORS': '8',
08:15:23 INFO - 'OS': 'Windows_NT',
08:15:23 INFO - 'OURDRIVE': 'C:',
08:15:23 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
08:15:23 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
08:15:23 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
08:15:23 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
08:15:23 INFO - 'PROCESSOR_LEVEL': '6',
08:15:23 INFO - 'PROCESSOR_REVISION': '1e05',
08:15:23 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
08:15:23 INFO - 'PROGRAMFILES': 'C:\\Program Files',
08:15:23 INFO - 'PROMPT': '$P$G',
08:15:23 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
08:15:23 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
08:15:23 INFO - 'PUBLIC': 'C:\\Users\\Public',
08:15:23 INFO - 'PWD': 'C:\\slave\\test',
08:15:23 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
08:15:23 INFO - 'SLAVEDIR': 'C:\\slave\\',
08:15:23 INFO - 'SYSTEMDRIVE': 'C:',
08:15:23 INFO - 'SYSTEMROOT': 'C:\\windows',
08:15:23 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
08:15:23 INFO - 'TEST1': 'testie',
08:15:23 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
08:15:23 INFO - 'USERDOMAIN': 'T-W732-IX-044',
08:15:23 INFO - 'USERNAME': 'cltbld',
08:15:23 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
08:15:23 INFO - 'WINDIR': 'C:\\windows',
08:15:23 INFO - 'WINDOWS_TRACING_FLAGS': '3',
08:15:23 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
08:15:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
08:15:24 INFO - Ignoring indexes: https://pypi.python.org/simple/
08:15:24 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages
08:15:24 INFO - Cleaning up...
08:15:24 INFO - Return code: 0
08:15:24 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv
08:15:24 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')]}
08:15:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
08:15:24 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')]}
08:15:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
08:15:24 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 0x01890640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01F4F020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0183B0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01943E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F315C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-044', 'USERDOMAIN': 'T-W732-IX-044', '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-044', '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
08:15:24 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
08:15:24 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
08:15:24 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
08:15:24 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
08:15:24 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
08:15:24 INFO - 'COMPUTERNAME': 'T-W732-IX-044',
08:15:24 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
08:15:24 INFO - 'CYGWINBASE': 'C:\\cygwin',
08:15:24 INFO - 'DCLOCATION': 'SCL3',
08:15:24 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
08:15:24 INFO - 'FP_NO_HOST_CHECK': 'NO',
08:15:24 INFO - 'HOMEDRIVE': 'C:',
08:15:24 INFO - 'HOMEPATH': '\\Users\\cltbld',
08:15:24 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
08:15:24 INFO - 'KTS_VERSION': '1.19c',
08:15:24 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
08:15:24 INFO - 'LOGONSERVER': '\\\\T-W732-IX-044',
08:15:24 INFO - 'MONDIR': 'C:\\Monitor_config\\',
08:15:24 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
08:15:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:15:24 INFO - 'MOZ_NO_REMOTE': '1',
08:15:24 INFO - 'NO_EM_RESTART': '1',
08:15:24 INFO - 'NUMBER_OF_PROCESSORS': '8',
08:15:24 INFO - 'OS': 'Windows_NT',
08:15:24 INFO - 'OURDRIVE': 'C:',
08:15:24 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;',
08:15:24 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
08:15:24 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
08:15:24 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
08:15:24 INFO - 'PROCESSOR_LEVEL': '6',
08:15:24 INFO - 'PROCESSOR_REVISION': '1e05',
08:15:24 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
08:15:24 INFO - 'PROGRAMFILES': 'C:\\Program Files',
08:15:24 INFO - 'PROMPT': '$P$G',
08:15:24 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
08:15:24 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
08:15:24 INFO - 'PUBLIC': 'C:\\Users\\Public',
08:15:24 INFO - 'PWD': 'C:\\slave\\test',
08:15:24 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
08:15:24 INFO - 'SLAVEDIR': 'C:\\slave\\',
08:15:24 INFO - 'SYSTEMDRIVE': 'C:',
08:15:24 INFO - 'SYSTEMROOT': 'C:\\windows',
08:15:24 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
08:15:24 INFO - 'TEST1': 'testie',
08:15:24 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
08:15:24 INFO - 'USERDOMAIN': 'T-W732-IX-044',
08:15:24 INFO - 'USERNAME': 'cltbld',
08:15:24 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
08:15:24 INFO - 'WINDIR': 'C:\\windows',
08:15:24 INFO - 'WINDOWS_TRACING_FLAGS': '3',
08:15:24 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
08:15:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
08:15:24 INFO - Ignoring indexes: https://pypi.python.org/simple/
08:15:24 INFO - Downloading/unpacking blobuploader==1.2.4
08:15:24 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
08:15:24 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
08:15:25 INFO - Downloading blobuploader-1.2.4.tar.gz
08:15:25 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader
08:15:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4)
08:15:25 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
08:15:25 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
08:15:25 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests
08:15:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4)
08:15:26 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
08:15:26 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
08:15:26 INFO - Downloading docopt-0.6.1.tar.gz
08:15:26 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt
08:15:26 INFO - Installing collected packages: blobuploader, requests, docopt
08:15:26 INFO - Running setup.py install for blobuploader
08:15:26 INFO - Running setup.py install for requests
08:15:27 INFO - Running setup.py install for docopt
08:15:27 INFO - Successfully installed blobuploader requests docopt
08:15:27 INFO - Cleaning up...
08:15:29 INFO - Return code: 0
08:15:29 INFO - Installing None into virtualenv C:\slave\test\build/venv
08:15:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
08:15:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
08:15:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
08:15:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
08:15:29 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 0x01890640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01F4F020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0183B0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01943E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F315C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-044', 'USERDOMAIN': 'T-W732-IX-044', '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-044', '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
08:15:29 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
08:15:29 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
08:15:29 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
08:15:29 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
08:15:29 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
08:15:29 INFO - 'COMPUTERNAME': 'T-W732-IX-044',
08:15:29 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
08:15:29 INFO - 'CYGWINBASE': 'C:\\cygwin',
08:15:29 INFO - 'DCLOCATION': 'SCL3',
08:15:29 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
08:15:29 INFO - 'FP_NO_HOST_CHECK': 'NO',
08:15:29 INFO - 'HOMEDRIVE': 'C:',
08:15:29 INFO - 'HOMEPATH': '\\Users\\cltbld',
08:15:29 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
08:15:29 INFO - 'KTS_VERSION': '1.19c',
08:15:29 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
08:15:29 INFO - 'LOGONSERVER': '\\\\T-W732-IX-044',
08:15:29 INFO - 'MONDIR': 'C:\\Monitor_config\\',
08:15:29 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
08:15:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:15:29 INFO - 'MOZ_NO_REMOTE': '1',
08:15:29 INFO - 'NO_EM_RESTART': '1',
08:15:29 INFO - 'NUMBER_OF_PROCESSORS': '8',
08:15:29 INFO - 'OS': 'Windows_NT',
08:15:29 INFO - 'OURDRIVE': 'C:',
08:15:29 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
08:15:29 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
08:15:29 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
08:15:29 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
08:15:29 INFO - 'PROCESSOR_LEVEL': '6',
08:15:29 INFO - 'PROCESSOR_REVISION': '1e05',
08:15:29 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
08:15:29 INFO - 'PROGRAMFILES': 'C:\\Program Files',
08:15:29 INFO - 'PROMPT': '$P$G',
08:15:29 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
08:15:29 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
08:15:29 INFO - 'PUBLIC': 'C:\\Users\\Public',
08:15:29 INFO - 'PWD': 'C:\\slave\\test',
08:15:29 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
08:15:29 INFO - 'SLAVEDIR': 'C:\\slave\\',
08:15:29 INFO - 'SYSTEMDRIVE': 'C:',
08:15:29 INFO - 'SYSTEMROOT': 'C:\\windows',
08:15:29 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
08:15:29 INFO - 'TEST1': 'testie',
08:15:29 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
08:15:29 INFO - 'USERDOMAIN': 'T-W732-IX-044',
08:15:29 INFO - 'USERNAME': 'cltbld',
08:15:29 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
08:15:29 INFO - 'WINDIR': 'C:\\windows',
08:15:29 INFO - 'WINDOWS_TRACING_FLAGS': '3',
08:15:29 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
08:15:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
08:15:29 INFO - Ignoring indexes: https://pypi.python.org/simple/
08:15:29 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
08:15:29 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-h4efaj-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
08:15:29 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
08:15:29 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cuo2za-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
08:15:29 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
08:15:29 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-65gnys-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
08:15:29 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
08:15:29 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-n0tk2w-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
08:15:29 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
08:15:29 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zvj4bl-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
08:15:30 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
08:15:30 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-scewil-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
08:15:30 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
08:15:30 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-abuqin-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
08:15:30 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
08:15:30 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-soorrq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
08:15:30 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
08:15:30 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-esylua-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
08:15:30 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
08:15:30 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-4xnuih-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
08:15:30 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
08:15:30 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ckomdt-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
08:15:30 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
08:15:30 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-yzv3m5-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
08:15:30 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
08:15:30 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-sgflc7-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
08:15:31 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
08:15:31 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-x8a1ij-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
08:15:31 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
08:15:31 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lidskq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
08:15:31 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
08:15:31 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ubt8fr-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
08:15:31 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
08:15:31 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vzicja-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
08:15:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
08:15:31 INFO - Running setup.py install for manifestparser
08:15:31 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts
08:15:31 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts
08:15:32 INFO - Running setup.py install for mozcrash
08:15:32 INFO - Running setup.py install for mozdebug
08:15:32 INFO - Running setup.py install for mozdevice
08:15:33 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts
08:15:33 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts
08:15:33 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts
08:15:33 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts
08:15:33 INFO - Running setup.py install for mozfile
08:15:33 INFO - Running setup.py install for mozhttpd
08:15:34 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts
08:15:34 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts
08:15:34 INFO - Running setup.py install for mozinfo
08:15:34 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts
08:15:34 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts
08:15:34 INFO - Running setup.py install for mozInstall
08:15:34 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts
08:15:34 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts
08:15:34 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts
08:15:34 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts
08:15:34 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts
08:15:34 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts
08:15:34 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts
08:15:34 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts
08:15:34 INFO - Running setup.py install for mozleak
08:15:35 INFO - Running setup.py install for mozlog
08:15:35 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts
08:15:35 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts
08:15:36 INFO - Running setup.py install for moznetwork
08:15:36 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts
08:15:36 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts
08:15:36 INFO - Running setup.py install for mozprocess
08:15:37 INFO - Running setup.py install for mozprofile
08:15:37 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts
08:15:37 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts
08:15:37 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts
08:15:37 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts
08:15:37 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts
08:15:37 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts
08:15:37 INFO - Running setup.py install for mozrunner
08:15:38 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts
08:15:38 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts
08:15:38 INFO - Running setup.py install for mozscreenshot
08:15:38 INFO - Running setup.py install for moztest
08:15:39 INFO - Running setup.py install for mozversion
08:15:39 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts
08:15:39 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts
08:15:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion
08:15:39 INFO - Cleaning up...
08:15:39 INFO - Return code: 0
08:15:39 INFO - Installing None into virtualenv C:\slave\test\build/venv
08:15:39 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')]}
08:15:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
08:15:39 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')]}
08:15:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
08:15:39 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 0x01890640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01F4F020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0183B0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01943E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F315C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-044', 'USERDOMAIN': 'T-W732-IX-044', '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-044', '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
08:15:39 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
08:15:39 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
08:15:39 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
08:15:39 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
08:15:39 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
08:15:39 INFO - 'COMPUTERNAME': 'T-W732-IX-044',
08:15:39 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
08:15:39 INFO - 'CYGWINBASE': 'C:\\cygwin',
08:15:39 INFO - 'DCLOCATION': 'SCL3',
08:15:39 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
08:15:39 INFO - 'FP_NO_HOST_CHECK': 'NO',
08:15:39 INFO - 'HOMEDRIVE': 'C:',
08:15:39 INFO - 'HOMEPATH': '\\Users\\cltbld',
08:15:39 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
08:15:39 INFO - 'KTS_VERSION': '1.19c',
08:15:39 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
08:15:39 INFO - 'LOGONSERVER': '\\\\T-W732-IX-044',
08:15:39 INFO - 'MONDIR': 'C:\\Monitor_config\\',
08:15:39 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
08:15:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:15:39 INFO - 'MOZ_NO_REMOTE': '1',
08:15:39 INFO - 'NO_EM_RESTART': '1',
08:15:39 INFO - 'NUMBER_OF_PROCESSORS': '8',
08:15:39 INFO - 'OS': 'Windows_NT',
08:15:39 INFO - 'OURDRIVE': 'C:',
08:15:39 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;',
08:15:39 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
08:15:39 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
08:15:39 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
08:15:39 INFO - 'PROCESSOR_LEVEL': '6',
08:15:39 INFO - 'PROCESSOR_REVISION': '1e05',
08:15:39 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
08:15:39 INFO - 'PROGRAMFILES': 'C:\\Program Files',
08:15:39 INFO - 'PROMPT': '$P$G',
08:15:39 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
08:15:39 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
08:15:39 INFO - 'PUBLIC': 'C:\\Users\\Public',
08:15:39 INFO - 'PWD': 'C:\\slave\\test',
08:15:39 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
08:15:39 INFO - 'SLAVEDIR': 'C:\\slave\\',
08:15:39 INFO - 'SYSTEMDRIVE': 'C:',
08:15:39 INFO - 'SYSTEMROOT': 'C:\\windows',
08:15:39 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
08:15:39 INFO - 'TEST1': 'testie',
08:15:39 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
08:15:39 INFO - 'USERDOMAIN': 'T-W732-IX-044',
08:15:39 INFO - 'USERNAME': 'cltbld',
08:15:39 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
08:15:39 INFO - 'WINDIR': 'C:\\windows',
08:15:39 INFO - 'WINDOWS_TRACING_FLAGS': '3',
08:15:39 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
08:15:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
08:15:40 INFO - Ignoring indexes: https://pypi.python.org/simple/
08:15:40 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
08:15:40 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-adwiho-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
08:15:40 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))
08:15:40 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
08:15:40 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-exyrbu-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
08:15:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///C:/slave/test/build/tests/mozbase/mozcrash in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2))
08:15:40 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
08:15:40 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-1warjc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
08:15:40 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))
08:15:40 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
08:15:40 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ewnydy-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
08:15:40 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))
08:15:40 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
08:15:40 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-5hbijf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
08:15:40 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))
08:15:40 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
08:15:40 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-sczsow-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
08:15:41 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))
08:15:41 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
08:15:41 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-izg_vr-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
08:15:41 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))
08:15:41 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
08:15:41 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mgl8la-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
08:15:41 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))
08:15:41 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
08:15:41 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jqnsyq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
08:15:41 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))
08:15:41 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
08:15:41 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ffoiwe-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
08:15:41 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))
08:15:41 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
08:15:41 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-nkzjjp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
08:15:41 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))
08:15:41 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
08:15:41 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-v2hopk-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
08:15:41 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))
08:15:41 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
08:15:41 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zgvsun-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
08:15:41 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))
08:15:41 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
08:15:42 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-neilkx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
08:15:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///C:/slave/test/build/tests/mozbase/mozrunner in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 14))
08:15:42 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
08:15:42 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-1rw353-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
08:15:42 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))
08:15:42 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
08:15:42 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fzfkks-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
08:15:42 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))
08:15:42 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
08:15:42 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-_dlofa-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
08:15:42 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))
08:15:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==1.0->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2))
08:15:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==1.0->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2))
08:15:42 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))
08:15:42 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))
08:15:42 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))
08:15:42 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10))
08:15:42 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
08:15:42 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
08:15:43 INFO - Downloading blessings-1.6.tar.gz
08:15:43 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings
08:15:44 INFO - Installing collected packages: blessings
08:15:44 INFO - Running setup.py install for blessings
08:15:44 INFO - Successfully installed blessings
08:15:44 INFO - Cleaning up...
08:15:46 INFO - Return code: 0
08:15:46 INFO - Done creating virtualenv C:\slave\test\build/venv.
08:15:46 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
08:15:46 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
08:15:47 INFO - Reading from file tmpfile_stdout
08:15:47 INFO - Using _rmtree_windows ...
08:15:47 INFO - Using _rmtree_windows ...
08:15:47 INFO - Current package versions:
08:15:47 INFO - blessings == 1.6
08:15:47 INFO - blobuploader == 1.2.4
08:15:47 INFO - docopt == 0.6.1
08:15:47 INFO - functools32 == 3.2.3-2
08:15:47 INFO - jsonschema == 2.5.1
08:15:47 INFO - manifestparser == 1.1
08:15:47 INFO - mozInstall == 1.12
08:15:47 INFO - mozcrash == 1.0
08:15:47 INFO - mozdebug == 0.1
08:15:47 INFO - mozdevice == 0.48
08:15:47 INFO - mozfile == 1.2
08:15:47 INFO - mozhttpd == 0.7
08:15:47 INFO - mozinfo == 0.9
08:15:47 INFO - mozleak == 0.1
08:15:47 INFO - mozlog == 3.4
08:15:47 INFO - moznetwork == 0.27
08:15:47 INFO - mozprocess == 0.23
08:15:47 INFO - mozprofile == 0.28
08:15:47 INFO - mozrunner == 6.13
08:15:47 INFO - mozscreenshot == 0.1
08:15:47 INFO - mozsystemmonitor == 0.3
08:15:47 INFO - moztest == 0.7
08:15:47 INFO - mozversion == 1.4
08:15:47 INFO - psutil == 3.1.1
08:15:47 INFO - requests == 1.2.3
08:15:47 INFO - Installing None into virtualenv C:\slave\test\build/venv
08:15:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
08:15:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
08:15:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
08:15:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
08:15:47 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 0x01890640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01F4F020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0183B0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01943E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F315C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-044', 'USERDOMAIN': 'T-W732-IX-044', '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-044', '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
08:15:47 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
08:15:47 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
08:15:47 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
08:15:47 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
08:15:47 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
08:15:47 INFO - 'COMPUTERNAME': 'T-W732-IX-044',
08:15:47 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
08:15:47 INFO - 'CYGWINBASE': 'C:\\cygwin',
08:15:47 INFO - 'DCLOCATION': 'SCL3',
08:15:47 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
08:15:47 INFO - 'FP_NO_HOST_CHECK': 'NO',
08:15:47 INFO - 'HOMEDRIVE': 'C:',
08:15:47 INFO - 'HOMEPATH': '\\Users\\cltbld',
08:15:47 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
08:15:47 INFO - 'KTS_VERSION': '1.19c',
08:15:47 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
08:15:47 INFO - 'LOGONSERVER': '\\\\T-W732-IX-044',
08:15:47 INFO - 'MONDIR': 'C:\\Monitor_config\\',
08:15:47 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
08:15:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:15:47 INFO - 'MOZ_NO_REMOTE': '1',
08:15:47 INFO - 'NO_EM_RESTART': '1',
08:15:47 INFO - 'NUMBER_OF_PROCESSORS': '8',
08:15:47 INFO - 'OS': 'Windows_NT',
08:15:47 INFO - 'OURDRIVE': 'C:',
08:15:47 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;',
08:15:47 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
08:15:47 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
08:15:47 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
08:15:47 INFO - 'PROCESSOR_LEVEL': '6',
08:15:47 INFO - 'PROCESSOR_REVISION': '1e05',
08:15:47 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
08:15:47 INFO - 'PROGRAMFILES': 'C:\\Program Files',
08:15:47 INFO - 'PROMPT': '$P$G',
08:15:47 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
08:15:47 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
08:15:47 INFO - 'PUBLIC': 'C:\\Users\\Public',
08:15:47 INFO - 'PWD': 'C:\\slave\\test',
08:15:47 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
08:15:47 INFO - 'SLAVEDIR': 'C:\\slave\\',
08:15:47 INFO - 'SYSTEMDRIVE': 'C:',
08:15:47 INFO - 'SYSTEMROOT': 'C:\\windows',
08:15:47 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
08:15:47 INFO - 'TEST1': 'testie',
08:15:47 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
08:15:47 INFO - 'USERDOMAIN': 'T-W732-IX-044',
08:15:47 INFO - 'USERNAME': 'cltbld',
08:15:47 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
08:15:47 INFO - 'WINDIR': 'C:\\windows',
08:15:47 INFO - 'WINDOWS_TRACING_FLAGS': '3',
08:15:47 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
08:15:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
08:15:47 INFO - Ignoring indexes: https://pypi.python.org/simple/
08:15:47 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))
08:15:47 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))
08:15:47 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))
08:15:47 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))
08:15:47 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))
08:15:47 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))
08:15:47 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))
08:15:47 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))
08:15:47 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))
08:15:47 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))
08:15:47 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))
08:15:47 INFO - Cleaning up...
08:15:47 INFO - Return code: 0
08:15:47 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv
08:15:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
08:15:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
08:15:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
08:15:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
08:15:47 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 0x01890640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01F4F020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0183B0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01943E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F315C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-044', 'USERDOMAIN': 'T-W732-IX-044', '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-044', '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
08:15:47 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
08:15:47 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
08:15:47 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
08:15:47 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
08:15:47 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
08:15:47 INFO - 'COMPUTERNAME': 'T-W732-IX-044',
08:15:47 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
08:15:47 INFO - 'CYGWINBASE': 'C:\\cygwin',
08:15:47 INFO - 'DCLOCATION': 'SCL3',
08:15:47 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
08:15:47 INFO - 'FP_NO_HOST_CHECK': 'NO',
08:15:47 INFO - 'HOMEDRIVE': 'C:',
08:15:47 INFO - 'HOMEPATH': '\\Users\\cltbld',
08:15:47 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
08:15:47 INFO - 'KTS_VERSION': '1.19c',
08:15:47 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
08:15:47 INFO - 'LOGONSERVER': '\\\\T-W732-IX-044',
08:15:47 INFO - 'MONDIR': 'C:\\Monitor_config\\',
08:15:47 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
08:15:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:15:47 INFO - 'MOZ_NO_REMOTE': '1',
08:15:47 INFO - 'NO_EM_RESTART': '1',
08:15:47 INFO - 'NUMBER_OF_PROCESSORS': '8',
08:15:47 INFO - 'OS': 'Windows_NT',
08:15:47 INFO - 'OURDRIVE': 'C:',
08:15:47 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;',
08:15:47 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
08:15:47 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
08:15:47 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
08:15:47 INFO - 'PROCESSOR_LEVEL': '6',
08:15:47 INFO - 'PROCESSOR_REVISION': '1e05',
08:15:47 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
08:15:47 INFO - 'PROGRAMFILES': 'C:\\Program Files',
08:15:47 INFO - 'PROMPT': '$P$G',
08:15:47 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
08:15:47 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
08:15:47 INFO - 'PUBLIC': 'C:\\Users\\Public',
08:15:47 INFO - 'PWD': 'C:\\slave\\test',
08:15:47 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
08:15:47 INFO - 'SLAVEDIR': 'C:\\slave\\',
08:15:47 INFO - 'SYSTEMDRIVE': 'C:',
08:15:47 INFO - 'SYSTEMROOT': 'C:\\windows',
08:15:47 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
08:15:47 INFO - 'TEST1': 'testie',
08:15:47 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
08:15:47 INFO - 'USERDOMAIN': 'T-W732-IX-044',
08:15:47 INFO - 'USERNAME': 'cltbld',
08:15:47 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
08:15:47 INFO - 'WINDIR': 'C:\\windows',
08:15:47 INFO - 'WINDOWS_TRACING_FLAGS': '3',
08:15:47 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
08:15:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
08:15:48 INFO - Ignoring indexes: https://pypi.python.org/simple/
08:15:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages
08:15:48 INFO - Cleaning up...
08:15:48 INFO - Return code: 0
08:15:48 INFO - Running post-action listener: _resource_record_post_action
08:15:48 INFO - Running post-action listener: _start_resource_monitoring
08:15:48 INFO - Starting resource monitoring.
08:15:48 INFO - [mozharness: 2016-11-16 16:15:48.221000Z] Finished create-virtualenv step (success)
08:15:48 INFO - [mozharness: 2016-11-16 16:15:48.221000Z] Running install step.
08:15:48 INFO - Running pre-action listener: _resource_record_pre_action
08:15:48 INFO - Running main action method: install
08:15:48 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
08:15:48 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
08:15:48 INFO - Reading from file tmpfile_stdout
08:15:48 INFO - Using _rmtree_windows ...
08:15:48 INFO - Using _rmtree_windows ...
08:15:48 INFO - Detecting whether we're running mozinstall >=1.0...
08:15:48 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h']
08:15:48 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h
08:15:48 INFO - Reading from file tmpfile_stdout
08:15:48 INFO - Output received:
08:15:48 INFO - Usage: mozinstall-script.py [options] installer
08:15:48 INFO - Options:
08:15:48 INFO - -h, --help show this help message and exit
08:15:48 INFO - -d DEST, --destination=DEST
08:15:48 INFO - Directory to install application into. [default:
08:15:48 INFO - "C:\slave\test"]
08:15:48 INFO - --app=APP Application being installed. [default: firefox]
08:15:48 INFO - Using _rmtree_windows ...
08:15:48 INFO - Using _rmtree_windows ...
08:15:48 INFO - mkdir: C:\slave\test\build\application
08:15:48 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', 'C:\\slave\\test\\build\\firefox-53.0a1.en-US.win32.zip', '--destination', 'C:\\slave\\test\\build\\application']
08:15:48 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py C:\slave\test\build\firefox-53.0a1.en-US.win32.zip --destination C:\slave\test\build\application
08:15:51 INFO - Reading from file tmpfile_stdout
08:15:51 INFO - Output received:
08:15:51 INFO - C:\slave\test\build\application\firefox\firefox.exe
08:15:51 INFO - Using _rmtree_windows ...
08:15:51 INFO - Using _rmtree_windows ...
08:15:51 INFO - Running post-action listener: _resource_record_post_action
08:15:51 INFO - [mozharness: 2016-11-16 16:15:51.173000Z] Finished install step (success)
08:15:51 INFO - [mozharness: 2016-11-16 16:15:51.173000Z] Running run-tests step.
08:15:51 INFO - Running pre-action listener: _resource_record_pre_action
08:15:51 INFO - Running main action method: run_tests
08:15:51 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version']
08:15:51 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version
08:15:51 INFO - Python 2.7.3
08:15:51 INFO - Return code: 0
08:15:51 INFO - grabbing minidump binary from tooltool
08:15: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')]}
08:15:51 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 0x01943E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F315C0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1
08:15:51 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
08:15:51 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
08:15:51 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
08:15:51 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'...
08:15:52 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmpfkbxal
08:15:52 INFO - INFO - File integrity verified, renaming tmpfkbxal to win32-minidump_stackwalk.exe
08:15:52 INFO - Return code: 0
08:15:52 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755
08:15:52 INFO - mkdir: C:\slave\test\build\blobber_upload_dir
08:15:52 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir
08:15:52 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe
08:15:52 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir
08:15:52 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VgybdUy1R_aArtn-RBlm7w/artifacts/public/build/firefox-53.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-044', '--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
08:15:52 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/VgybdUy1R_aArtn-RBlm7w/artifacts/public/build/firefox-53.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-044 --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
08:15:52 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
08:15:52 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
08:15:52 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
08:15:52 INFO - 'COMPUTERNAME': 'T-W732-IX-044',
08:15:52 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
08:15:52 INFO - 'CYGWINBASE': 'C:\\cygwin',
08:15:52 INFO - 'DCLOCATION': 'SCL3',
08:15:52 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
08:15:52 INFO - 'FP_NO_HOST_CHECK': 'NO',
08:15:52 INFO - 'HOMEDRIVE': 'C:',
08:15:52 INFO - 'HOMEPATH': '\\Users\\cltbld',
08:15:52 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
08:15:52 INFO - 'KTS_VERSION': '1.19c',
08:15:52 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
08:15:52 INFO - 'LOGONSERVER': '\\\\T-W732-IX-044',
08:15:52 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir',
08:15:52 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe',
08:15:52 INFO - 'MONDIR': 'C:\\Monitor_config\\',
08:15:52 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
08:15:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:15:52 INFO - 'MOZ_NO_REMOTE': '1',
08:15:52 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir',
08:15:52 INFO - 'NO_EM_RESTART': '1',
08:15:52 INFO - 'NUMBER_OF_PROCESSORS': '8',
08:15:52 INFO - 'OS': 'Windows_NT',
08:15:52 INFO - 'OURDRIVE': 'C:',
08:15:52 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
08:15:52 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
08:15:52 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
08:15:52 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
08:15:52 INFO - 'PROCESSOR_LEVEL': '6',
08:15:52 INFO - 'PROCESSOR_REVISION': '1e05',
08:15:52 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
08:15:52 INFO - 'PROGRAMFILES': 'C:\\Program Files',
08:15:52 INFO - 'PROMPT': '$P$G',
08:15:52 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
08:15:52 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
08:15:52 INFO - 'PUBLIC': 'C:\\Users\\Public',
08:15:52 INFO - 'PWD': 'C:\\slave\\test',
08:15:52 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos',
08:15:52 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
08:15:52 INFO - 'SLAVEDIR': 'C:\\slave\\',
08:15:52 INFO - 'SYSTEMDRIVE': 'C:',
08:15:52 INFO - 'SYSTEMROOT': 'C:\\windows',
08:15:52 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
08:15:52 INFO - 'TEST1': 'testie',
08:15:52 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
08:15:52 INFO - 'USERDOMAIN': 'T-W732-IX-044',
08:15:52 INFO - 'USERNAME': 'cltbld',
08:15:52 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
08:15:52 INFO - 'WINDIR': 'C:\\windows',
08:15:52 INFO - 'WINDOWS_TRACING_FLAGS': '3',
08:15:52 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
08:15:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
08:15:52 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VgybdUy1R_aArtn-RBlm7w/artifacts/public/build/firefox-53.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-044', '--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
08:15:53 INFO - mozversion application_buildid: 20161116062433
08:15:53 INFO - mozversion application_changeset: 51750761f2c61c64cf0553f6cb5fefd4999d3bc0
08:15:53 INFO - mozversion application_display_name: Nightly
08:15:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
08:15:53 INFO - mozversion application_name: Firefox
08:15:53 INFO - mozversion application_remotingname: firefox
08:15:53 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central
08:15:53 INFO - mozversion application_vendor: Mozilla
08:15:53 INFO - mozversion application_version: 53.0a1
08:15:53 INFO - mozversion platform_buildid: 20161116062433
08:15:53 INFO - mozversion platform_changeset: 51750761f2c61c64cf0553f6cb5fefd4999d3bc0
08:15:53 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central
08:15:53 INFO - mozversion platform_version: 53.0a1
08:15:53 INFO - using testdate: 1479312953
08:15:53 INFO - actual date: 1479312953
08:15:53 INFO - starting webserver on 'localhost:49902'
08:15:53 INFO - SUITE-START | Running 2 tests
08:15:53 INFO - TEST-START | dromaeo_css
08:15:53 INFO - operating with platform_type : w7_
08:15:53 INFO - Initialising browser for dromaeo_css test...
08:15:53 INFO - TEST-INFO | started process 2560 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpc6xii4\profile http://localhost:49902/getInfo.html)
08:16:01 INFO - PROCESS | 2560 | __metrics Screen width/height:1600/1200
08:16:01 INFO - PROCESS | 2560 | colorDepth:24
08:16:01 INFO - PROCESS | 2560 | Browser inner width/height: 1010/674
08:16:01 INFO - PROCESS | 2560 | __metrics
08:16:02 INFO - PROCESS | 2560 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows"
08:16:02 INFO - PROCESS | 2560 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
08:16:03 INFO - TEST-INFO | 2560: exit 0
08:16:03 INFO - Browser initialized.
08:16:03 INFO - Running cycle 1/1 for dromaeo_css test...
08:16:03 INFO - TEST-INFO | started process 2564 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpc6xii4\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1)
08:16:04 INFO - PROCESS | 2564 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0
08:16:15 INFO - PROCESS | 2564 | RSS: Main: 137752576
08:16:15 INFO - PROCESS | 2564 |
08:17:06 INFO - PROCESS | 2564 | 1479313026732 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]
08:19:20 INFO - PROCESS | 2564 | Cycle 1(1): loaded http://localhost:49902/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49902/tests/dromaeo/cssquery-ext.html)
08:19:22 INFO - PROCESS | 2564 | RSS: Main: 257179648
08:19:22 INFO - PROCESS | 2564 |
08:22:25 INFO - PROCESS | 2564 | Cycle 1(1): loaded http://localhost:49902/tests/dromaeo/cssquery-ext.html (next: http://localhost:49902/tests/dromaeo/cssquery-jquery.html)
08:22:26 INFO - PROCESS | 2564 | RSS: Main: 206614528
08:22:26 INFO - PROCESS | 2564 |
08:25:28 INFO - PROCESS | 2564 | Cycle 1(1): loaded http://localhost:49902/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49902/tests/dromaeo/cssquery-mootools.html)
08:25:29 INFO - PROCESS | 2564 | RSS: Main: 150515712
08:25:29 INFO - PROCESS | 2564 |
08:28:31 INFO - PROCESS | 2564 | Cycle 1(1): loaded http://localhost:49902/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49902/tests/dromaeo/cssquery-prototype.html)
08:28:32 INFO - PROCESS | 2564 | RSS: Main: 138805248
08:28:32 INFO - PROCESS | 2564 |
08:31:35 INFO - PROCESS | 2564 | Cycle 1(1): loaded http://localhost:49902/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49902/tests/dromaeo/cssquery-yui.html)
08:31:36 INFO - PROCESS | 2564 | RSS: Main: 140345344
08:31:36 INFO - PROCESS | 2564 |
08:34:39 INFO - PROCESS | 2564 | Cycle 1(1): loaded http://localhost:49902/tests/dromaeo/cssquery-yui.html (next: http://localhost:49902/tests/dromaeo/cssquery-dojo.html)
08:34:39 INFO - PROCESS | 2564 | RSS: Main: 192782336
08:34:39 INFO - PROCESS | 2564 |
08:34:39 INFO - PROCESS | 2564 | __start_tp_report
08:34:39 INFO - PROCESS | 2564 | _x_x_mozilla_page_load
08:34:39 INFO - PROCESS | 2564 | _x_x_mozilla_page_load_details
08:34:39 INFO - PROCESS | 2564 | |i|pagename|runs|
08:34:39 INFO - PROCESS | 2564 | |0;dojo.html;2177.6080305545;2341.0635745701716;2355.6819829323035;2369.8459600126002;2371.7983971362432;4024.657904078153;4034.5763694812063;4053;4122.175564887023;4131.504219493658;3973.2053589282186;3995.06116062725;4052.4731784867927;4114.485689288839;4145.834166633331;3530.964690353102;3555.2000799820025;3585.9641403585892;3586.605473397924;3615.475756015376;3555.946660800077;3565;3600.765950213228;3615.5842078160954;3616.27674465108;3612.4942508048894;3628.383174860267;3638.890833275006;3643.872464463744;3656.6708996190337;3303.306305675811;3416.590009198912;3569.714422846179;3617.0595645132194;3627.673509384168;3492.0396890854963;3531.2760884018717;3547.7871327720163;3549.716022718176;3577.731670124751;4007.9599204007877;4011.4785077939973;4030.6372426481757;4090.8159132839096;4095.5904409558807;4096.160287141129;4131.958680413188;4132.442120313779;4224.577542245751;4292.377605247253;4235.068284977331;4247.8300867965245;4291.184674911757;4331.480222373335;4332.025294308787;3956.4263181839046;3957.5053118359915;4018.6584140347813;4022.4770779798437;4036.1524079943542;4094.7952602369764;4125.958740412558;4323.3947247385395;4388.407564978751;4399.472063352418;4088.2232375848494;4123.525794533607;4131.132462182975;4132.690048246333;4135.731177473454;4177.895552611209;4197.9370309445385;4201.663866890642;4203.4115223868075;4207.76857272853;4095.877123686294;4131.235721391552;4162.188373267184;4167.333226683716;4172.4784401949755;3969.9876531484288;4094.0788322627172;4154.356074808409;4160.084781348159;4162.895927601774;2959.245392424918;2961.4077184562743;2982.0606508949613;2985.11938978002;2985.447692176954;2708.2146177608665;2754.5179593571047;2755.9527379595625;2764.557670772667;2765.184270640166;3903.33643280643;3997.6402123809;4018.819153138116;4043.1509383029893;4050.5544390117066;4166.645835103989;4180.3102488089735;4200.810963506649;4237.194932962727;4302.053548219449;3911.882643520699;3954.1103251768122;3956.9208615827524;3972.56301806801;4000.899977500586;3454.4990976308795;3611.115276757211;3655.141041855162;3664.853405863789;3667.651573100551;4321.956780432155;4344.9348259776125;4433.911321773547;4436.5119836817275;4459.197344478025;4138.379243113557;4181.0592616662225;4262.083652014831;4360.847370342023;4365.755759608496;4253.149370125925;4268.935965960575;4287.678424118141;4294.205571969195;4304.203722311382;4119.382092686121;4126.051008268055;4127.442795222727;4141.855035073758;4145.54399016114;4020.437138800629;4041.5554289028773;4192.958070419257;4197.727147735387;4199.874003779892;3969.9603003970747;4080.7755573443746;4090.4273401723185;4095.610916962884;4169.937450938299;4061.4110953910927;4076.653484453736;4177.060161463646;4179.728317659343;4184.163167366478;3879.3405121128912;3933.233019561158;4026.9395959060053;4063.9390409143293;4111.465509483748;4056.4320995061307;4094.8157332919495;4101.036256479784;4107.075907920694;4118.299889018814;4026.1142548639255;4063.451434056483;4064.1262128642484;4064.634182923551;4065.410515475299
08:34:39 INFO - PROCESS | 2564 | |1;ext.html;536.7638239174763;539.1077766296779;554.548043344674;555.6138483753792;555.6777069299801;24906;33741.66258337416;33955.49066763998;34275.31449371014;35934.64065359346;33001.50497742536;34484.65515344846;34672.65327346726;34700.27445471494;34804.30391392171;6941.340572645616;7072.504924655276;7074.186468556105;7081.362677359037;7082.114735658043;6959.164900211982;6998.895016574756;7017.789466316019;7030.648467576626;7032.261612530688;7025.718971241145;7043.26045765195;7045.3306935841265;7050.576965382068;7054.118235220598;7004.719811207546;7024.1571011478945;7047.189573199096;7049.964750176267;7053.788386348418;6809.489288303397;6960;7007.299270073003;7012.123484564429;7019.473539484508;31808.681913180804;32034.679653203402;32044.03867883967;32068.198295042577;32112.357752845044;2436.9634455483006;2439.097533912446;2595.9091431799798;2614.2157352794084;2640.923875742289;23347;23557.29328120159;24841.378965525826;25044.248672539852;25277;3012.834294113823;3108.797928134664;3138.5135304027913;3172.825494597796;3236.64396916339;202231;202748;283008;285628;287362;43892;43987.56012439899;44532.33201501915;44534;44579.77710111429;44749;46197;46247.53752462432;46459.767701161276;46615.30077048847;11103.555857765761;11137;11266.43667816606;11275.154363422776;11455.026322762658;8435.493870367798;8551.273141783018;8685.348598855111;8692.652293908173;8701.564921754014;15340;15540.300686469136;15683.607909802346;15699.860027994448;15810.920945395199;6231.906521402183;6360.204974378203;6431.967840160863;6439.5170362223025;6449.677516124175;740.174705203694;763.7785042337771;769.4729110559285;848.1052489623506;871.7820544863785;22898.542029159322;23410.297691069296;23894.28317150488;23913.7608623915;24227.39431514191;832.7876391520124;837.6900546797602;862.642003568512;883.2139395937493;949.6533765175661;12403.193792403465;12471.688207794878;12686.541047779623;12881.613551593467;12985.870141298656;274225;283612;285818;285889;285965;41297;41346.58653413427;41568.168636627095;41701.58298416977;41750.58249417528;883.3992884838243;931.2736065868634;1017.5624481472889;1039.101177481473;1057.735566108473;24126.51746965086;24480;24653.929415503426;24774.876125619256;25574.488510230065;38429.231415370974;39184.41223381594;39793;39794.60205397909;39857;230148;274925;275671;276208;413812;24227.273181804576;30194.547081793353;31689.36621267608;32890.83554582212;33639.83180084084;35283.82358088193;35287.64712352843;35367.64632353746;35621.465678014334;35732.46401304034;30376.544351835182;30803.84598076995;31059.84470077635;31138.22154446157;31626.683733162376;29776.553351700175;30422.543661844647;30614.540781888736;31269.8436507816;31373.52939705952;29239.386880682912;29801;29828.10515684533;29872.551911720908;30233.395332093674;27128;27561.862190688917;27964.860175698992;28048.29879253035;28402;20790;22377.552448951257;22397;22600.66099008549;22636.773632263466
08:34:39 INFO - PROCESS | 2564 | |2;jquery.html;270.3740839955504;294.5993448909483;301.1102193019627;306.5478419331485;310.58381768430314;11087.72280692983;11662.358570278642;14683.265836708175;15158.924205378986;15550.455734049283;13687.863121368784;13787.242551489691;13900.374483148258;13974.650633734162;14006.159630422157;4454.354118652802;4676.6492513061485;4676.953230467686;4678.157931572316;4678.719276843383;4523.321501774743;4653.092646933849;4743.478217396101;4752.453467851189;4758.666893317469;4712;4806.879828004293;4807.35100761398;4812.927806082912;4814.566688997989;4776.737279449629;4787.0904528139545;4787.6648634595595;4788;4794.736289504076;4551.180787458256;4803.471618122004;4820.517948205186;4823.107725070873;4824.396950381202;10507.842382364272;10656.413897235649;10680.839787403194;10687.572497100107;13284.402201900937;1727.6199236168022;1777.5111844242936;2029.6752519596794;2103.9158433662633;2174.608570457317;9057.411268267542;11517.424128793611;11881.287122772575;12382.504699811996;12470.376481175905;1593.7609358596187;1606.991965040179;1612.9677406451806;1648.2418087679682;1669.7912760904887;60217.39782602162;63705.362946370406;65264;65781.67109164472;66071.66964165197;7956.283934445928;8082.9191708083345;8118.878216826753;8222.62998165084;8243.670253189925;9018.278537716968;9675.322727409013;9704.320697551244;9738.367006144577;11440.885591143982;3987.840486380512;4057.0668746188544;4085.162541678949;4091.4476545665952;5501.834944951658;5515.917261241085;5617.8033768817895;5619.8595035124445;5648.237487939158;5749.195112684228;3481.1819222482695;3509.824508774551;3513.350030244413;3574.267275208576;3576.6959808416577;1267.9049071319503;1502.504173622702;1504.736671082556;1510.7507261301982;1516.385863725193;626.7947321517019;759.8024513626386;766.2835249042164;792.3463142907125;801.1747899663239;14224.28878556068;14301.57095287143;14934.626634334229;15041.473548425753;15076.396944122112;707.487071872888;728.3117454005913;740.6481921087548;822.2969361196091;834.1075049696888;8233.588320583947;8346.707865224687;8391.454555453876;8452.957735211285;8640.740777776677;270694;273399;276461;277814;278214.2625008115;58113;68589;69348;71531.2846871535;71596.64201678958;749.1796482851269;770.5684816502776;771.1093686791764;771.5987686403151;772.127495929596;13353;13877.930610346884;14144.43422263098;14176.574702758933;14447.855521444651;36349.818250908575;36490;36789;36929.63070369369;36976.26047479089;48733.269000964305;49483.75258123686;49788;49931.50068499269;50772.49227507677;5678.801241956512;6483.643399613066;6548.541602087808;6650.135482387453;6672.966635166793;9648.276379271443;10579.841302380317;10699.679009629723;10733.838992414963;10859.2398532102;8801.691940782042;9252.259819214312;9274.44353338802;9318.813623727623;9459.148676619137;8863;8917.317653368887;9209.355345125778;9299.349045566745;9499.240060795259;8719.258862996716;8856.690015849414;8907.821843562962;9504.95247523758;9721.4653194075;5542;5854.502367298827;5903.439173278532;5950.404959504015;6703.631300278532;6615.834604134935;9102.907651081912;9151.908480915106;9269.768255793659;9334.766630834283
08:34:39 INFO - PROCESS | 2564 | |3;mootools.html;982.3761911186394;1129.0459561670386;1150.4535345710785;1200.8078707406814;1328.7209685965947;4951.628627852912;5604.243427137335;5745.253117094772;5803.158542011406;5816.505597024247;5828.621139625921;5874.970625146868;5876.147958546008;5879.0593505039205;5880.353161152269;1756.2272600055935;1797.5236562310997;1800.3878681248373;1803.5851754096566;1805.1425572852856;1739.5651087228193;1775.9556011099696;1788.204249109147;1792.4264235444664;1793.157216108427;1744.206386094324;1767.3990843113338;1770.6812773700728;1771.840534351908;1773.1488885335048;1649.3567508671629;1654.9987257709033;1657.776200212974;1658.3615308106346;1689.898606083639;1641.6880792649479;1660.1699150424788;1671.0642040457383;1683.8989660620402;1683.9494815155565;5539.639923404966;5601.467860553282;5640.294963129609;5658.179563963202;5705.71471426427;3322.401967645823;3476.374252634525;3483.89548313551;3484.529588505545;3514.420120680085;2283.075354481421;2288.954220915589;2326.7091613548305;2333.428310064045;2336.4042169246904;671.8656268746222;673.6732684647942;704.0635954180927;715.8174665460326;716.4197000429668;7387.261273872569;7430.888536671954;7464.589447580381;7471.402287817016;7478.102627684658;3002.4295383876993;3102.3329984053535;3146.197719581538;3157.2896098377682;3170.25499007733;5703.230063941398;5724.769174627475;5756.42435756421;5758.280214973129;5773.336066352339;3299.785513941586;3326.1185785766793;3401.200717831308;3402.4556071028514;3409.0625078103226;2742.561190209557;2845.601615773793;3225.1130938991496;3257.153140183569;3308.3052558962886;2590.2747230775412;2651.0058727977007;2652.270625577943;2685.0602289199014;2685.3823620567377;840.9873851892227;860.324645153564;867.4535042922918;868.9217970382696;869.143893265146;593.0392763722797;646.018052560106;651.8044586623994;689.0594338727701;701.312713540733;3816.694664426839;3850.172212974168;3867.7485963412287;3871.109644781716;3928.1161738608585;727.3780917315619;727.5489196698081;735.2427000189813;740.4742632730821;759.4152502572988;2698.1500827239356;2768.7231276872553;2770.1689493152376;2799.5520716685232;2829.4765468388414;6463.741450342028;6495.870082598416;6596.4393026592315;6597.373249541286;6863.2793556676825;6332.968335158294;6465.191851018622;6485.189351331083;6508.8047358579315;6553.770618028346;5182.455842136596;5334.546563542065;5359.651622644516;5360.061989151883;5381.946180538144;4006.338954072545;4036.414719865662;4054.108096218827;4101.384792281182;4109.609587089221;6265.185525881606;6366.076918846648;6401.263854656774;6409;6411.166548348685;6576.934230657632;6591.472682185509;6604.438622717123;6609.1408116944485;6628.370304821035;4647.697899636512;4789.521047895182;4875.414950205998;4896.706197628154;4903;4825.420949486084;4952.232403977389;5181.3005244291535;5634.464725851038;5652.378238393856;5069.543741063322;5104.923426148685;5127.4872512748425;5159;5215.530602245816;4900.191468407673;4968.527989840959;5047.2681461187185;5085.7457127144235;5106.744662766772;4796.112719146969;4825.469198388104;4844.176489996779;4848.854534363974;4862.6839255448285;3246.4967929970894;3262.2986057997646;3264.428724973073;3291.226561758033;3326.850291736926;3357.983210083934;3448.7241020718784;3493.3013397321147;3504.859805607747;3551.5738111426795
08:34:39 INFO - PROCESS | 2564 | |4;prototype.html;226.76076739040326;228.6969765061294;231.81107397471064;233.21755510263068;237.20536203717538;4390.934135987959;4401.36180253863;4411.338299255105;4416.425864637601;4421.381006659066;4028.6172813582734;4175.436316097326;4238.237117318881;4327.459067616548;4353.542877997812;3164.0033389482296;3218.8229647369385;3223.2747631782895;3226.483762597985;3229.2411283348392;3135.5139953307275;3195.2491164576304;3216.324571839905;3218.6781321867743;3230.854611542486;3006.864691088895;3099.7520198384186;3118.610173728284;3174.7460203183805;3177.8252196129206;3119.407312610597;3191.420246977738;3192.632847222576;3199.6640352762856;3202.4235637585457;2781.207355903578;2787.902423415191;2788.860556972143;2790.6651201855702;2793.6787269464066;4242.681798865097;4255.616994470482;4258.6380157686635;4266.317389217741;4277.930517370657;3479.5302634144573;3694.9261014779822;3698.519192504984;3703.6481534254203;3721.6278372162838;4194.860621083239;4449.087936972913;4465.352523884051;4469.597736203757;4488.842890498817;2900.9709902900913;3008.293051132982;3012.1565961530805;3012.5029370153898;3038.4378889905215;4556.977215113903;4559.4072770539615;4620.607248383892;4631.235846085358;4673.25227963531;3017.170278173476;3094.675059118805;3095.5511450839504;3102.534619807047;3103.67411421802;3108.347247078094;3119.984400077985;3125.2186953261685;3126.3122113135073;3128.264857758425;3442.294329662413;3575.2313252650806;3648.7810731356203;3668.6881615062484;3680.4847321375028;4470.955290447054;4481.350204220435;4503.819847206075;4503.977480112579;4507.932381014288;956.9138777509918;1037.9481025948671;1042.166266986408;1043.5877828257796;1043.6973277749369;644.4876323322876;683.733343995842;692.2558249881398;693.1889689063808;697.8848489999093;4111.301078816608;4113.568075352045;4133.379993001074;4159.188958153131;4178.749275043507;4487.237169681162;4512.887177820581;4514.435695538058;4531.229690952546;4540.526497229622;4135.627793498599;4156.9792151039055;4183.477065366829;4186.413902053654;4205.978970105191;2293.472501324671;2382.023370418155;2383.295943400477;2390.5816482115906;2395.425097976473;4566.173530587725;4568.291914753218;4569;4590.898184435711;4618.468876079294;4565.315202719552;4576.656750743708;4577.359169716243;4584.724916504953;4594.7702614869795;4506.211413002777;4523.90952180948;4564.520725323793;4608.5621865922685;4611.2852507861335;4210.284251677161;4467.419235499365;4489.73061616304;4492.146492166478;4494.280915053576;4390.165868484977;4395.1868904252815;4399.956000440083;4406.625436837905;4407.36093266481;4574.222382195101;4578.6108180805;4593.6554758392585;4606.516315786928;4607.792649330855;2984.820910745362;2993.386355797013;2996.5804787329357;2997.5054116070605;3005.579218909311;4374.650027997689;4411.911761764811;4436.866893993185;4444.888877778081;4447.44339481179;4153.439285696513;4298.9570104298555;4469.575390337913;4503.459584849839;4503.729776213438;4417.757023363746;4484.506704262463;4489.438820147481;4505.707129036602;4517.593416592523;4320.632746216606;4418.4918734345965;4427.02997166258;4427.645788336861;4439.512763224227;4452.777361131865;4520.434945631796;4550.6587005974025;4567.269236922077;4567.337736028191;4126.319157339006;4206.590792084686;4409.382686423839;4468.352088947149;4501.527339629291
08:34:39 INFO - PROCESS | 2564 | |5;yui.html;526.4919352824523;543.8966596346694;568.2981517825485;585.8388943040665;663.3797399431531;11450.885491145096;12256.387180640966;12283.508659653626;12290.754184916308;12303.384830758449;10826.917308269169;11209.383483908381;11389.772204555924;11594.594189203379;11673.708157296072;598.6408155106944;618.5453691536717;620.609016319718;628.0485065126337;628.0767272110014;626.4768917953492;631.1542533005771;631.1889222349282;631.2046821005544;633.1231244726065;654.4829584628138;658.568637542408;662.053263832719;664.64773669955;664.7772996046331;630.8328293002359;647.9092926990226;650.1612919334053;655.2693746472672;655.3217419970332;481.55456203012074;481.91084649339984;482.91790395632825;483.3330004593673;484.02469024914825;10596.576136954513;10639.468026598717;10644.148468122532;10648.946755266252;10680.51937662807;9502;9635.373700709431;9684.56419461126;9868.60525578976;9952.601895924226;8271.834563308761;8577.52823594702;8628.956855215683;8642.265407440374;8973.461592304482;2877.9712202878122;2906.229849089993;2910.4033673096965;2953.8966136185345;3038.453078445879;34122.829385852914;48901;48978.7551062246;50012;50023.749881250726;8920.8661870072;9088.500132492776;9105.590248438835;9322.627094916263;9399.859002114837;15326.923365383325;15762.842371576138;15817.288222030036;15844.68310633804;15873.365065397486;6175.938240617626;6254.749810007549;6259.561830671901;6302.086197501429;6385.872282554323;3268.8692452301643;3283.9014829555153;3291.243014106721;3414.197663549064;3424.760266781351;2229.810466110366;2285.3029825902927;2287.7597852225276;2298.8505747126383;2299.7930186283315;382.8162482008596;383.1532313587004;386.7872670031471;390.2078780076449;391.14925038042145;447.776111944028;480.2532062642624;542.9619926605179;566.6939852479697;575.6574837971484;7312.305330993547;7454.254574542502;7460.253974602605;7584.886226706604;7657.770266892002;537.3256563013472;542.9158480435539;550.7218854478463;574.7528562717988;587.1838144978483;3545.6099829018785;3588.5873124591008;3653.214558869856;3707.6107008763693;3710.3692372296773;44419;49632.75183624059;52211;52307.47692523102;67049;37924.810375948495;52560.73719631377;52752.20871686851;53119.73440132775;53983.460165397846;31954.84022579872;39383.40924886129;42611;48692;49662.75168624134;7492.925070749223;7806.57063861493;7951.642176102205;7958.118303870631;8036.758897233092;34523.82738086294;35795.92612221638;35906.99999999895;36400.45399318959;36742;44265.77867110644;47332;47560.52439475699;47692.323074768865;48615.756921215165;9244.214241789523;9397.718068457958;9482.95258523703;9525;10018.3488073275;11277.7744445109;11487.13846461502;11623.360715160747;11787.587434439753;11820.586279480522;10992.890071099506;11026.889731102587;11028.724281893017;11136.49885755159;11422.086233101498;10361.378317300987;10849.005002337088;11054.557817687524;11075.667729968114;11285.097192224766;10306.948465257627;10320.27758056959;10533.157347412036;10535.7001347176;10753.354798712102;9008.594613242289;9509.476978766234;9511.334206605748;9680.273979451711;9715.417074975525;9033.412828166149;9073.364864459425;9258.120478554527;9450.574724137292;9500.904990950003
08:34:39 INFO - PROCESS | 2564 | __end_tp_report
08:34:39 INFO - PROCESS | 2564 | __start_cc_report
08:34:39 INFO - PROCESS | 2564 | _x_x_mozilla_cycle_collect,2349
08:34:39 INFO - PROCESS | 2564 | __end_cc_report
08:34:39 INFO - PROCESS | 2564 | __startTimestamp1479314079348__endTimestamp
08:34:39 INFO - PROCESS | 2564 |
08:34:39 INFO - PROCESS | 2564 | ------- Summary: start -------
08:34:39 INFO - PROCESS | 2564 | Number of tests: 6
08:34:39 INFO - PROCESS | 2564 |
08:34:39 INFO - PROCESS | 2564 | [#0] dojo.html Cycles:165 Average:3901.27 Median:4064.63 stddev:462.38 (11.4%) stddev-sans-first:443.59
08:34:39 INFO - PROCESS | 2564 | Values: 2177.6 2341.1 2355.7 2369.8 2371.8 4024.7 4034.6 4053.0 4122.2 4131.5 3973.2 3995.1 4052.5 4114.5 4145.8 3531.0 3555.2 3586.0 3586.6 3615.5 3555.9 3565.0 3600.8 3615.6 3616.3 3612.5 3628.4 3638.9 3643.9 3656.7 3303.3 3416.6 3569.7 3617.1 3627.7 3492.0 3531.3 3547.8 3549.7 3577.7 4008.0 4011.5 4030.6 4090.8 4095.6 4096.2 4132.0 4132.4 4224.6 4292.4 4235.1 4247.8 4291.2 4331.5 4332.0 3956.4 3957.5 4018.7 4022.5 4036.2 4094.8 4126.0 4323.4 4388.4 4399.5 4088.2 4123.5 4131.1 4132.7 4135.7 4177.9 4197.9 4201.7 4203.4 4207.8 4095.9 4131.2 4162.2 4167.3 4172.5 3970.0 4094.1 4154.4 4160.1 4162.9 2959.2 2961.4 2982.1 2985.1 2985.4 2708.2 2754.5 2756.0 2764.6 2765.2 3903.3 3997.6 4018.8 4043.2 4050.6 4166.6 4180.3 4200.8 4237.2 4302.1 3911.9 3954.1 3956.9 3972.6 4000.9 3454.5 3611.1 3655.1 3664.9 3667.7 4322.0 4344.9 4433.9 4436.5 4459.2 4138.4 4181.1 4262.1 4360.8 4365.8 4253.1 4268.9 4287.7 4294.2 4304.2 4119.4 4126.1 4127.4 4141.9 4145.5 4020.4 4041.6 4193.0 4197.7 4199.9 3970.0 4080.8 4090.4 4095.6 4169.9 4061.4 4076.7 4177.1 4179.7 4184.2 3879.3 3933.2 4026.9 4063.9 4111.5 4056.4 4094.8 4101.0 4107.1 4118.3 4026.1 4063.5 4064.1 4064.6 4065.4
08:34:39 INFO - PROCESS | 2564 |
08:34:39 INFO - PROCESS | 2564 | [#1] ext.html Cycles:180 Average:41068.08 Median:18063.74 stddev:73746.07 (408.3%) stddev-sans-first:73890.15
08:34:39 INFO - PROCESS | 2564 | Values: 536.8 539.1 554.5 555.6 555.7 24906.0 33741.7 33955.5 34275.3 35934.6 33001.5 34484.7 34672.7 34700.3 34804.3 6941.3 7072.5 7074.2 7081.4 7082.1 6959.2 6998.9 7017.8 7030.6 7032.3 7025.7 7043.3 7045.3 7050.6 7054.1 7004.7 7024.2 7047.2 7050.0 7053.8 6809.5 6960.0 7007.3 7012.1 7019.5 31808.7 32034.7 32044.0 32068.2 32112.4 2437.0 2439.1 2595.9 2614.2 2640.9 23347.0 23557.3 24841.4 25044.2 25277.0 3012.8 3108.8 3138.5 3172.8 3236.6 202231.0 202748.0 283008.0 285628.0 287362.0 43892.0 43987.6 44532.3 44534.0 44579.8 44749.0 46197.0 46247.5 46459.8 46615.3 11103.6 11137.0 11266.4 11275.2 11455.0 8435.5 8551.3 8685.3 8692.7 8701.6 15340.0 15540.3 15683.6 15699.9 15810.9 6231.9 6360.2 6432.0 6439.5 6449.7 740.2 763.8 769.5 848.1 871.8 22898.5 23410.3 23894.3 23913.8 24227.4 832.8 837.7 862.6 883.2 949.7 12403.2 12471.7 12686.5 12881.6 12985.9 274225.0 283612.0 285818.0 285889.0 285965.0 41297.0 41346.6 41568.2 41701.6 41750.6 883.4 931.3 1017.6 1039.1 1057.7 24126.5 24480.0 24653.9 24774.9 25574.5 38429.2 39184.4 39793.0 39794.6 39857.0 230148.0 274925.0 275671.0 276208.0 413812.0 24227.3 30194.5 31689.4 32890.8 33639.8 35283.8 35287.6 35367.6 35621.5 35732.5 30376.5 30803.8 31059.8 31138.2 31626.7 29776.6 30422.5 30614.5 31269.8 31373.5 29239.4 29801.0 29828.1 29872.6 30233.4 27128.0 27561.9 27964.9 28048.3 28402.0 20790.0 22377.6 22397.0 22600.7 22636.8
08:34:39 INFO - PROCESS | 2564 |
08:34:39 INFO - PROCESS | 2564 | [#2] jquery.html Cycles:180 Average:19528.24 Median:4805.18 stddev:46270.66 (962.9%) stddev-sans-first:46377.87
08:34:39 INFO - PROCESS | 2564 | Values: 270.4 294.6 301.1 306.5 310.6 11087.7 11662.4 14683.3 15158.9 15550.5 13687.9 13787.2 13900.4 13974.7 14006.2 4454.4 4676.6 4677.0 4678.2 4678.7 4523.3 4653.1 4743.5 4752.5 4758.7 4712.0 4806.9 4807.4 4812.9 4814.6 4776.7 4787.1 4787.7 4788.0 4794.7 4551.2 4803.5 4820.5 4823.1 4824.4 10507.8 10656.4 10680.8 10687.6 13284.4 1727.6 1777.5 2029.7 2103.9 2174.6 9057.4 11517.4 11881.3 12382.5 12470.4 1593.8 1607.0 1613.0 1648.2 1669.8 60217.4 63705.4 65264.0 65781.7 66071.7 7956.3 8082.9 8118.9 8222.6 8243.7 9018.3 9675.3 9704.3 9738.4 11440.9 3987.8 4057.1 4085.2 4091.4 5501.8 5515.9 5617.8 5619.9 5648.2 5749.2 3481.2 3509.8 3513.4 3574.3 3576.7 1267.9 1502.5 1504.7 1510.8 1516.4 626.8 759.8 766.3 792.3 801.2 14224.3 14301.6 14934.6 15041.5 15076.4 707.5 728.3 740.6 822.3 834.1 8233.6 8346.7 8391.5 8453.0 8640.7 270694.0 273399.0 276461.0 277814.0 278214.3 58113.0 68589.0 69348.0 71531.3 71596.6 749.2 770.6 771.1 771.6 772.1 13353.0 13877.9 14144.4 14176.6 14447.9 36349.8 36490.0 36789.0 36929.6 36976.3 48733.3 49483.8 49788.0 49931.5 50772.5 5678.8 6483.6 6548.5 6650.1 6673.0 9648.3 10579.8 10699.7 10733.8 10859.2 8801.7 9252.3 9274.4 9318.8 9459.1 8863.0 8917.3 9209.4 9299.3 9499.2 8719.3 8856.7 8907.8 9505.0 9721.5 5542.0 5854.5 5903.4 5950.4 6703.6 6615.8 9102.9 9151.9 9269.8 9334.8
08:34:39 INFO - PROCESS | 2564 |
08:34:39 INFO - PROCESS | 2564 | [#3] mootools.html Cycles:180 Average:3747.59 Median:4855.77 stddev:1974.83 (40.7%) stddev-sans-first:1969.43
08:34:39 INFO - PROCESS | 2564 | Values: 982.4 1129.0 1150.5 1200.8 1328.7 4951.6 5604.2 5745.3 5803.2 5816.5 5828.6 5875.0 5876.1 5879.1 5880.4 1756.2 1797.5 1800.4 1803.6 1805.1 1739.6 1776.0 1788.2 1792.4 1793.2 1744.2 1767.4 1770.7 1771.8 1773.1 1649.4 1655.0 1657.8 1658.4 1689.9 1641.7 1660.2 1671.1 1683.9 1683.9 5539.6 5601.5 5640.3 5658.2 5705.7 3322.4 3476.4 3483.9 3484.5 3514.4 2283.1 2289.0 2326.7 2333.4 2336.4 671.9 673.7 704.1 715.8 716.4 7387.3 7430.9 7464.6 7471.4 7478.1 3002.4 3102.3 3146.2 3157.3 3170.3 5703.2 5724.8 5756.4 5758.3 5773.3 3299.8 3326.1 3401.2 3402.5 3409.1 2742.6 2845.6 3225.1 3257.2 3308.3 2590.3 2651.0 2652.3 2685.1 2685.4 841.0 860.3 867.5 868.9 869.1 593.0 646.0 651.8 689.1 701.3 3816.7 3850.2 3867.7 3871.1 3928.1 727.4 727.5 735.2 740.5 759.4 2698.2 2768.7 2770.2 2799.6 2829.5 6463.7 6495.9 6596.4 6597.4 6863.3 6333.0 6465.2 6485.2 6508.8 6553.8 5182.5 5334.5 5359.7 5360.1 5381.9 4006.3 4036.4 4054.1 4101.4 4109.6 6265.2 6366.1 6401.3 6409.0 6411.2 6576.9 6591.5 6604.4 6609.1 6628.4 4647.7 4789.5 4875.4 4896.7 4903.0 4825.4 4952.2 5181.3 5634.5 5652.4 5069.5 5104.9 5127.5 5159.0 5215.5 4900.2 4968.5 5047.3 5085.7 5106.7 4796.1 4825.5 4844.2 4848.9 4862.7 3246.5 3262.3 3264.4 3291.2 3326.9 3358.0 3448.7 3493.3 3504.9 3551.6
08:34:39 INFO - PROCESS | 2564 |
08:34:39 INFO - PROCESS | 2564 | [#4] prototype.html Cycles:180 Average:3672.81 Median:4272.12 stddev:1124.60 (26.3%) stddev-sans-first:1097.61
08:34:39 INFO - PROCESS | 2564 | Values: 226.8 228.7 231.8 233.2 237.2 4390.9 4401.4 4411.3 4416.4 4421.4 4028.6 4175.4 4238.2 4327.5 4353.5 3164.0 3218.8 3223.3 3226.5 3229.2 3135.5 3195.2 3216.3 3218.7 3230.9 3006.9 3099.8 3118.6 3174.7 3177.8 3119.4 3191.4 3192.6 3199.7 3202.4 2781.2 2787.9 2788.9 2790.7 2793.7 4242.7 4255.6 4258.6 4266.3 4277.9 3479.5 3694.9 3698.5 3703.6 3721.6 4194.9 4449.1 4465.4 4469.6 4488.8 2901.0 3008.3 3012.2 3012.5 3038.4 4557.0 4559.4 4620.6 4631.2 4673.3 3017.2 3094.7 3095.6 3102.5 3103.7 3108.3 3120.0 3125.2 3126.3 3128.3 3442.3 3575.2 3648.8 3668.7 3680.5 4471.0 4481.4 4503.8 4504.0 4507.9 956.9 1037.9 1042.2 1043.6 1043.7 644.5 683.7 692.3 693.2 697.9 4111.3 4113.6 4133.4 4159.2 4178.7 4487.2 4512.9 4514.4 4531.2 4540.5 4135.6 4157.0 4183.5 4186.4 4206.0 2293.5 2382.0 2383.3 2390.6 2395.4 4566.2 4568.3 4569.0 4590.9 4618.5 4565.3 4576.7 4577.4 4584.7 4594.8 4506.2 4523.9 4564.5 4608.6 4611.3 4210.3 4467.4 4489.7 4492.1 4494.3 4390.2 4395.2 4400.0 4406.6 4407.4 4574.2 4578.6 4593.7 4606.5 4607.8 2984.8 2993.4 2996.6 2997.5 3005.6 4374.7 4411.9 4436.9 4444.9 4447.4 4153.4 4299.0 4469.6 4503.5 4503.7 4417.8 4484.5 4489.4 4505.7 4517.6 4320.6 4418.5 4427.0 4427.6 4439.5 4452.8 4520.4 4550.7 4567.3 4567.3 4126.3 4206.6 4409.4 4468.4 4501.5
08:34:39 INFO - PROCESS | 2564 |
08:34:39 INFO - PROCESS | 2564 | [#5] yui.html Cycles:180 Average:12880.10 Median:48939.88 stddev:15711.13 (32.1%) stddev-sans-first:15727.82
08:34:39 INFO - PROCESS | 2564 | Values: 526.5 543.9 568.3 585.8 663.4 11450.9 12256.4 12283.5 12290.8 12303.4 10826.9 11209.4 11389.8 11594.6 11673.7 598.6 618.5 620.6 628.0 628.1 626.5 631.2 631.2 631.2 633.1 654.5 658.6 662.1 664.6 664.8 630.8 647.9 650.2 655.3 655.3 481.6 481.9 482.9 483.3 484.0 10596.6 10639.5 10644.1 10648.9 10680.5 9502.0 9635.4 9684.6 9868.6 9952.6 8271.8 8577.5 8629.0 8642.3 8973.5 2878.0 2906.2 2910.4 2953.9 3038.5 34122.8 48901.0 48978.8 50012.0 50023.7 8920.9 9088.5 9105.6 9322.6 9399.9 15326.9 15762.8 15817.3 15844.7 15873.4 6175.9 6254.7 6259.6 6302.1 6385.9 3268.9 3283.9 3291.2 3414.2 3424.8 2229.8 2285.3 2287.8 2298.9 2299.8 382.8 383.2 386.8 390.2 391.1 447.8 480.3 543.0 566.7 575.7 7312.3 7454.3 7460.3 7584.9 7657.8 537.3 542.9 550.7 574.8 587.2 3545.6 3588.6 3653.2 3707.6 3710.4 44419.0 49632.8 52211.0 52307.5 67049.0 37924.8 52560.7 52752.2 53119.7 53983.5 31954.8 39383.4 42611.0 48692.0 49662.8 7492.9 7806.6 7951.6 7958.1 8036.8 34523.8 35795.9 35907.0 36400.5 36742.0 44265.8 47332.0 47560.5 47692.3 48615.8 9244.2 9397.7 9483.0 9525.0 10018.3 11277.8 11487.1 11623.4 11787.6 11820.6 10992.9 11026.9 11028.7 11136.5 11422.1 10361.4 10849.0 11054.6 11075.7 11285.1 10306.9 10320.3 10533.2 10535.7 10753.4 9008.6 9509.5 9511.3 9680.3 9715.4 9033.4 9073.4 9258.1 9450.6 9500.9
08:34:39 INFO - PROCESS | 2564 | -------- Summary: end --------
08:34:39 INFO - PROCESS | 2564 |
08:34:40 INFO - TEST-INFO | 2564: exit 0
08:34:40 INFO - TEST-OK | dromaeo_css | took 1127139ms
08:34:40 INFO - TEST-START | kraken
08:34:40 INFO - operating with platform_type : w7_
08:34:40 INFO - Initialising browser for kraken test...
08:34:40 INFO - TEST-INFO | started process 4056 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpbgozv6\profile http://localhost:49902/getInfo.html)
08:34:45 INFO - PROCESS | 4056 | __metrics Screen width/height:1600/1200
08:34:45 INFO - PROCESS | 4056 | colorDepth:24
08:34:45 INFO - PROCESS | 4056 | Browser inner width/height: 1010/674
08:34:45 INFO - PROCESS | 4056 | __metrics
08:34:46 INFO - PROCESS | 4056 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows"
08:34:46 INFO - PROCESS | 4056 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
08:34:46 INFO - PROCESS | 4056 | console.error:
08:34:46 INFO - PROCESS | 4056 | Message: Error: Connection closed before committing the transaction.
08:34:46 INFO - PROCESS | 4056 | Stack:
08:34:46 INFO - PROCESS | 4056 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:932:23
08:34:46 INFO - PROCESS | 4056 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:813:7
08:34:46 INFO - PROCESS | 4056 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:744:11
08:34:46 INFO - PROCESS | 4056 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:776:7
08:34:46 INFO - PROCESS | 4056 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:711:7
08:34:46 INFO - PROCESS | 4056 | TaskImpl_run@resource://gre/modules/Task.jsm:324:15
08:34:46 INFO - PROCESS | 4056 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:550:9
08:34:46 INFO - PROCESS | 4056 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15
08:34:46 INFO - PROCESS | 4056 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:386:5
08:34:46 INFO - PROCESS | 4056 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29
08:34:46 INFO - PROCESS | 4056 | Barrier/this.client.addBlocker/promise13.2%
08:35:47 INFO - TinderboxPrint: I/O read bytes / time
74,964,992 / 809,110
08:35:47 INFO - TinderboxPrint: I/O write bytes / time
476,504,064 / 3,345,120
08:35:47 INFO - TinderboxPrint: CPU idle
8,320.4 (86.8%)
08:35:47 INFO - TinderboxPrint: CPU user
1,230.3 (12.8%)
08:35:47 INFO - install - Wall time: 3s; CPU: 11.0%; Read bytes: 81920; Write bytes: 36112384; Read time: 34830; Write time: 197480
08:35:47 INFO - run-tests - Wall time: 1196s; CPU: 13.0%; Read bytes: 74883072; Write bytes: 402944000; Read time: 774280; Write time: 2881470
08:35:47 INFO - Running post-run listener: _upload_blobber_files
08:35:47 INFO - Blob upload gear active.
08:35:47 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir.
08:35:47 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
08:35:47 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\venv\\Scripts\\blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', 'C:\\slave\\test\\oauth.txt', '-b', 'Firefox-Non-PGO', '-d', 'C:\\slave\\test\\build\\blobber_upload_dir', '--output-manifest', 'C:\\slave\\test\\build\\uploaded_files.json']
08:35:47 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\venv\Scripts\blobberc.py -u https://blobupload.elasticbeanstalk.com -a C:\slave\test\oauth.txt -b Firefox-Non-PGO -d C:\slave\test\build\blobber_upload_dir --output-manifest C:\slave\test\build\uploaded_files.json
08:35:48 INFO - (blobuploader) - INFO - Open directory for files ...
08:35:48 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log ...
08:35:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
08:35:48 INFO - (blobuploader) - INFO - Uploading, attempt #1.
08:35:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded
08:35:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
08:35:49 INFO - (blobuploader) - INFO - Done attempting.
08:35:49 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log ...
08:35:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
08:35:49 INFO - (blobuploader) - INFO - Uploading, attempt #1.
08:35:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded
08:35:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
08:35:50 INFO - (blobuploader) - INFO - Done attempting.
08:35:50 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ...
08:35:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
08:35:50 INFO - (blobuploader) - INFO - Uploading, attempt #1.
08:35:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
08:35:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
08:35:57 INFO - (blobuploader) - INFO - Done attempting.
08:35:57 INFO - (blobuploader) - INFO - Iteration through files over.
08:35:57 INFO - Return code: 0
08:35:57 INFO - rmtree: C:\slave\test\build\uploaded_files.json
08:35:57 INFO - Using _rmtree_windows ...
08:35:57 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1
08:35:57 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/861042d5de985e4e5c06faa63df57807b24059172694c11926b4e167ee300df526b4cfccb3cb31c41e53ea5bfad272549063a9bedc054dba03118d56575daf69", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3fe6666bf41a2a40bdd450b1b8fc106ed1f154d91212843eb265ddca227d29465a25f93b052fbc778b027fd9dec7c1b4465c05fe1ff0dc850f6dd8580a344692", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/defbe71b8a29b88d0847270747bfacfe3d7711eda1988afd88904841438e2c3e8f56323c237cb704b4541928fd94476ef32316819d8d2fdd4f6bf86aca93bbe3"}
08:35:57 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files
08:35:57 INFO - Writing to file C:\slave\test\properties\blobber_files
08:35:57 INFO - Contents:
08:35:57 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/861042d5de985e4e5c06faa63df57807b24059172694c11926b4e167ee300df526b4cfccb3cb31c41e53ea5bfad272549063a9bedc054dba03118d56575daf69", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3fe6666bf41a2a40bdd450b1b8fc106ed1f154d91212843eb265ddca227d29465a25f93b052fbc778b027fd9dec7c1b4465c05fe1ff0dc850f6dd8580a344692", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/defbe71b8a29b88d0847270747bfacfe3d7711eda1988afd88904841438e2c3e8f56323c237cb704b4541928fd94476ef32316819d8d2fdd4f6bf86aca93bbe3"}
08:35:57 INFO - Running post-run listener: copy_logs_to_upload_dir
08:35:57 INFO - Copying logs to upload dir...
08:35:57 INFO - mkdir: C:\slave\test\build\upload\logs
08:35:57 INFO - Copying logs to upload dir...
08:35:57 INFO - Using _rmtree_windows ...
08:35:57 INFO - Using _rmtree_windows ...
08:35:57 INFO - Using _rmtree_windows ...
08:35:57 INFO - Using _rmtree_windows ...
08:35:57 INFO - Using _rmtree_windows ...
08:35:57 INFO - Using _rmtree_windows ...
08:35:57 INFO - Using _rmtree_windows ...
program finished with exit code 0
elapsedTime=1317.553000
========= master_lag: 4.40 =========
========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 22 mins, 1 secs) (at 2016-11-16 08:36:02.295003) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-16 08:36:02.300997) =========
'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-044
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-044
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-044
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/861042d5de985e4e5c06faa63df57807b24059172694c11926b4e167ee300df526b4cfccb3cb31c41e53ea5bfad272549063a9bedc054dba03118d56575daf69", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3fe6666bf41a2a40bdd450b1b8fc106ed1f154d91212843eb265ddca227d29465a25f93b052fbc778b027fd9dec7c1b4465c05fe1ff0dc850f6dd8580a344692", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/defbe71b8a29b88d0847270747bfacfe3d7711eda1988afd88904841438e2c3e8f56323c237cb704b4541928fd94476ef32316819d8d2fdd4f6bf86aca93bbe3"}
build_url:https://queue.taskcluster.net/v1/task/VgybdUy1R_aArtn-RBlm7w/artifacts/public/build/firefox-53.0a1.en-US.win32.zip
program finished with exit code 0
elapsedTime=0.101000
build_url: 'https://queue.taskcluster.net/v1/task/VgybdUy1R_aArtn-RBlm7w/artifacts/public/build/firefox-53.0a1.en-US.win32.zip'
blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/861042d5de985e4e5c06faa63df57807b24059172694c11926b4e167ee300df526b4cfccb3cb31c41e53ea5bfad272549063a9bedc054dba03118d56575daf69", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3fe6666bf41a2a40bdd450b1b8fc106ed1f154d91212843eb265ddca227d29465a25f93b052fbc778b027fd9dec7c1b4465c05fe1ff0dc850f6dd8580a344692", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/defbe71b8a29b88d0847270747bfacfe3d7711eda1988afd88904841438e2c3e8f56323c237cb704b4541928fd94476ef32316819d8d2fdd4f6bf86aca93bbe3"}'
========= master_lag: 0.01 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-16 08:36:02.416232) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-16 08:36:02.416584) =========
'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-044
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-044
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-044
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-11-16 08:36:02.531171) =========
========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-11-16 08:36:02.531512) =========
========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-11-16 08:36:02.919594) =========
========= Total master_lag: 4.51 =========