builder: autoland_win7_ix_test-dromaeojs
slave: t-w732-ix-117
starttime: 1475098419.34
results: success (0)
buildid: 20160928130720
builduid: 79587fdb083348abad3d258830409ba2
revision: f36d1ab003c857b9861e3ca111c7e152e2a86af3
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-28 14:33:39.337420) =========
master: http://buildbot-master126.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-28 14:33:39.337813) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-28 14:33:39.338082) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-28 14:33:39.436408) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-28 14:33:39.436842) =========
'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.T-W732-IX-117\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-117
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.T-W732-IX-117
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOCALAPPDATA=C:\Users\cltbld.T-W732-IX-117\AppData\Local
LOGONSERVER=\\T-W732-IX-117
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~1.T-W\AppData\Local\Temp
TEST1=testie
TMP=C:\Users\CLTBLD~1.T-W\AppData\Local\Temp
USERDOMAIN=T-W732-IX-117
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld.T-W732-IX-117
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
--14:33:40-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
=> `archiver_client.py'
Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25
Connecting to hg.mozilla.org|63.245.215.102|:443... connected.
WARNING: Certificate verification error for hg.mozilla.org: certificate signature failure
HTTP request sent, awaiting response... 200 Script output follows
Length: 12,141 (12K) [text/x-python]
0K .......... . 100% 2.10 GB/s
14:33:40 (2.10 GB/s) - `archiver_client.py' saved [12141/12141]
program finished with exit code 0
elapsedTime=1.284000
========= master_lag: 0.08 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-28 14:33:40.798421) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 10 secs) (at 2016-09-28 14:33:40.798766) =========
'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.T-W732-IX-117\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-117
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.T-W732-IX-117
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOCALAPPDATA=C:\Users\cltbld.T-W732-IX-117\AppData\Local
LOGONSERVER=\\T-W732-IX-117
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~1.T-W\AppData\Local\Temp
TEST1=testie
TMP=C:\Users\CLTBLD~1.T-W\AppData\Local\Temp
USERDOMAIN=T-W732-IX-117
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld.T-W732-IX-117
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=10.401000
========= master_lag: 0.01 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 10 secs) (at 2016-09-28 14:33:51.210561) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-28 14:33:51.210978) =========
'bash' '-c' u'python archiver_client.py mozharness --repo integration/autoland --rev f36d1ab003c857b9861e3ca111c7e152e2a86af3 --destination scripts --debug'
in dir C:\slave\test\. (timeout 1200 secs)
watching logfiles {}
argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev f36d1ab003c857b9861e3ca111c7e152e2a86af3 --destination scripts --debug']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld.T-W732-IX-117\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-117
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.T-W732-IX-117
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOCALAPPDATA=C:\Users\cltbld.T-W732-IX-117\AppData\Local
LOGONSERVER=\\T-W732-IX-117
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~1.T-W\AppData\Local\Temp
TEST1=testie
TMP=C:\Users\CLTBLD~1.T-W\AppData\Local\Temp
USERDOMAIN=T-W732-IX-117
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld.T-W732-IX-117
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
2016-09-28 14:33:51,578 truncating revision to first 12 chars
2016-09-28 14:33:51,578 Setting DEBUG logging.
2016-09-28 14:33:51,578 attempt 1/10
2016-09-28 14:33:51,578 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/f36d1ab003c8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2016-09-28 14:33:52,094 unpacking tar archive at: autoland-f36d1ab003c8/testing/mozharness/
2016-09-28 14:33:52,298 skipping symlink on windows: configs/single_locale/linux32.py
program finished with exit code 0
elapsedTime=1.295000
========= master_lag: 0.10 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-28 14:33:52.603505) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-28 14:33:52.603823) =========
script_repo_revision: f36d1ab003c857b9861e3ca111c7e152e2a86af3
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-28 14:33:52.604220) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-28 14:33:52.604500) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-28 14:33:52.614926) =========
========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 0 secs) (at 2016-09-28 14:33:52.615207) =========
'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Autoland-Non-PGO' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Autoland-Non-PGO'
in dir C:\slave\test\. (timeout 3600 secs) (maxTime 7200 secs)
watching logfiles {}
argv: ['c:/mozilla-build/python27/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland-Non-PGO', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland-Non-PGO']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld.T-W732-IX-117\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-117
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.T-W732-IX-117
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOCALAPPDATA=C:\Users\cltbld.T-W732-IX-117\AppData\Local
LOGONSERVER=\\T-W732-IX-117
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~1.T-W\AppData\Local\Temp
TEST1=testie
TMP=C:\Users\CLTBLD~1.T-W\AppData\Local\Temp
USERDOMAIN=T-W732-IX-117
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld.T-W732-IX-117
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
14:33:53 INFO - MultiFileLogger online at 20160928 14:33:53 in C:\slave\test
14:33:53 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO
14:33:53 INFO - Dumping config to C:\slave\test\logs\localconfig.json.
14:33:53 INFO - {'append_to_log': False,
14:33:53 INFO - 'base_work_dir': 'C:\\slave\\test',
14:33:53 INFO - 'blob_upload_branch': 'Autoland-Non-PGO',
14:33:53 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt',
14:33:53 INFO - 'branch': 'Autoland-Non-PGO',
14:33:53 INFO - 'buildbot_json_path': 'buildprops.json',
14:33:53 INFO - 'config_files': ('talos/windows_config.py',),
14:33:53 INFO - 'default_actions': ('clobber',
14:33:53 INFO - 'read-buildbot-config',
14:33:53 INFO - 'download-and-extract',
14:33:53 INFO - 'populate-webroot',
14:33:53 INFO - 'create-virtualenv',
14:33:53 INFO - 'install',
14:33:53 INFO - 'run-tests'),
14:33:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
14:33:53 INFO - 'download_minidump_stackwalk': True,
14:33:53 INFO - 'download_symbols': 'ondemand',
14:33:53 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python',
14:33:53 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'),
14:33:53 INFO - 'hg': 'c:/mozilla-build/hg/hg',
14:33:53 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python',
14:33:53 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'),
14:33:53 INFO - 'python': 'c:/mozilla-build/python27/python.exe',
14:33:53 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe',
14:33:53 INFO - 'C:/mozilla-build/tooltool.py'),
14:33:53 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe',
14:33:53 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')},
14:33:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
14:33:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
14:33:53 INFO - 'installer_path': 'installer.exe',
14:33:53 INFO - 'log_level': 'info',
14:33:53 INFO - 'log_name': 'talos',
14:33:53 INFO - 'log_to_console': True,
14:33:53 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe',
14:33:53 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe',
14:33:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest',
14:33:53 INFO - 'opt_config_files': (),
14:33:53 INFO - 'pip_index': False,
14:33:53 INFO - 'sps_profile': False,
14:33:53 INFO - 'sps_profile_interval': 0,
14:33:53 INFO - 'suite': 'dromaeojs',
14:33:53 INFO - 'system_bits': '32',
14:33:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
14:33:53 INFO - 'title': 'T-W732-IX-117',
14:33:53 INFO - 'use_talos_json': True,
14:33:53 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'),
14:33:53 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv',
14:33:53 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll',
14:33:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
14:33:53 INFO - 'work_dir': 'build'}
14:33:53 INFO - [mozharness: 2016-09-28 21:33:53.015000Z] Running clobber step.
14:33:53 INFO - Running pre-action listener: _resource_record_pre_action
14:33:53 INFO - Running main action method: clobber
14:33:53 INFO - rmtree: C:\slave\test\build
14:33:53 INFO - Using _rmtree_windows ...
14:33:53 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1
14:35:07 INFO - Running post-action listener: _resource_record_post_action
14:35:07 INFO - [mozharness: 2016-09-28 21:35:07.757000Z] Finished clobber step (success)
14:35:07 INFO - [mozharness: 2016-09-28 21:35:07.757000Z] Running read-buildbot-config step.
14:35:07 INFO - Running pre-action listener: _resource_record_pre_action
14:35:07 INFO - Running main action method: read_buildbot_config
14:35:07 INFO - Using buildbot properties:
14:35:07 INFO - {
14:35:07 INFO - "project": "",
14:35:07 INFO - "product": "firefox",
14:35:07 INFO - "script_repo_revision": "production",
14:35:07 INFO - "builddir": "autoland_win7_ix_test-dromaeojs",
14:35:07 INFO - "repository": "",
14:35:07 INFO - "buildername": "Windows 7 32-bit autoland talos dromaeojs",
14:35:07 INFO - "buildid": "20160928130720",
14:35:07 INFO - "pgo_build": "False",
14:35:07 INFO - "basedir": "C:\\slave\\test",
14:35:07 INFO - "buildnumber": 229,
14:35:07 INFO - "slavename": "t-w732-ix-117",
14:35:07 INFO - "master": "http://buildbot-master126.bb.releng.scl3.mozilla.com:8201/",
14:35:07 INFO - "platform": "win7_ix",
14:35:07 INFO - "scheduler": "tests-autoland-win32-talos",
14:35:07 INFO - "branch": "autoland",
14:35:07 INFO - "repo_path": "integration/autoland",
14:35:07 INFO - "revision": "f36d1ab003c857b9861e3ca111c7e152e2a86af3",
14:35:07 INFO - "stage_platform": "win32",
14:35:07 INFO - "builduid": "79587fdb083348abad3d258830409ba2",
14:35:07 INFO - "slavebuilddir": "test"
14:35:07 INFO - }
14:35:07 INFO - Found installer url https://queue.taskcluster.net/v1/task/ff45gAeUSMmiMPeyn1GlEA/artifacts/public/build/firefox-52.0a1.en-US.win32.zip.
14:35:07 INFO - Running post-action listener: _resource_record_post_action
14:35:07 INFO - [mozharness: 2016-09-28 21:35:07.761000Z] Finished read-buildbot-config step (success)
14:35:07 INFO - [mozharness: 2016-09-28 21:35:07.761000Z] Running download-and-extract step.
14:35:07 INFO - Running pre-action listener: _resource_record_pre_action
14:35:07 INFO - Running main action method: download_and_extract
14:35:07 INFO - mkdir: C:\slave\test\build\tests
14:35: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')]}
14:35:07 INFO - trying https://queue.taskcluster.net/v1/task/ff45gAeUSMmiMPeyn1GlEA/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json
14:35:07 INFO - Downloading https://queue.taskcluster.net/v1/task/ff45gAeUSMmiMPeyn1GlEA/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json to C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json
14:35:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ff45gAeUSMmiMPeyn1GlEA/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json', 'file_name': 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.test_packages.json'}, attempt #1
14:35:08 INFO - Downloaded 1274 bytes.
14:35:08 INFO - Reading from file C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json
14:35:08 INFO - Using the following test package requirements:
14:35:08 INFO - {u'common': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
14:35:08 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
14:35:08 INFO - u'firefox-52.0a1.en-US.win32.cppunittest.tests.zip'],
14:35:08 INFO - u'gtest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
14:35:08 INFO - u'firefox-52.0a1.en-US.win32.gtest.tests.zip'],
14:35:08 INFO - u'jittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
14:35:08 INFO - u'jsshell-win32.zip'],
14:35:08 INFO - u'mochitest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
14:35:08 INFO - u'firefox-52.0a1.en-US.win32.mochitest.tests.zip'],
14:35:08 INFO - u'mozbase': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
14:35:08 INFO - u'reftest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
14:35:08 INFO - u'firefox-52.0a1.en-US.win32.reftest.tests.zip'],
14:35:08 INFO - u'talos': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
14:35:08 INFO - u'firefox-52.0a1.en-US.win32.talos.tests.zip'],
14:35:08 INFO - u'web-platform': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
14:35:08 INFO - u'firefox-52.0a1.en-US.win32.web-platform.tests.zip'],
14:35:08 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
14:35:08 INFO - u'firefox-52.0a1.en-US.win32.xpcshell.tests.zip']}
14:35:08 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip'] for test suite category: common
14:35:08 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/ff45gAeUSMmiMPeyn1GlEA/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip
14:35:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ff45gAeUSMmiMPeyn1GlEA/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1
14:35:08 INFO - Fetch https://queue.taskcluster.net/v1/task/ff45gAeUSMmiMPeyn1GlEA/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory
14:35:09 INFO - Http code: 200
14:35:09 INFO - Content-Encoding: None
14:35:09 INFO - Content-Type: application/zip
14:35:09 INFO - via: 1.1 d8f42fc9558e3e49ebfdf8834baeb756.cloudfront.net (CloudFront)
14:35:09 INFO - x-amz-cf-id: _SsEVRUAsQHDjeKkV7cI2mzWsTItfDJhZTqzPn9Db5k8odyYYz2G8A==
14:35:09 INFO - x-amz-version-id: 3bLR0cOcZJl4leEN13hmXl664JxpBQZl
14:35:09 INFO - x-cache: Hit from cloudfront
14:35:10 INFO - Expected file size: 17325044
14:35:10 INFO - Obtained file size: 17325044
14:35:34 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip', u'firefox-52.0a1.en-US.win32.talos.tests.zip'] for test suite category: talos
14:35:34 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/ff45gAeUSMmiMPeyn1GlEA/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip
14:35:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ff45gAeUSMmiMPeyn1GlEA/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1
14:35:34 INFO - Fetch https://queue.taskcluster.net/v1/task/ff45gAeUSMmiMPeyn1GlEA/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory
14:35:35 INFO - Http code: 200
14:35:35 INFO - Content-Encoding: None
14:35:35 INFO - Content-Type: application/zip
14:35:35 INFO - via: 1.1 032d42677fbf05c24a4bb71369581429.cloudfront.net (CloudFront)
14:35:35 INFO - x-amz-cf-id: AHNEA567l0TgT5Enlck7OPOsMepTLw5etNEPnRV-F4GuUkSQ9tjuiQ==
14:35:35 INFO - x-amz-version-id: 3bLR0cOcZJl4leEN13hmXl664JxpBQZl
14:35:35 INFO - x-cache: Hit from cloudfront
14:35:35 INFO - Expected file size: 17325044
14:35:35 INFO - Obtained file size: 17325044
14:35:42 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/ff45gAeUSMmiMPeyn1GlEA/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip
14:35:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ff45gAeUSMmiMPeyn1GlEA/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip'}, attempt #1
14:35:42 INFO - Fetch https://queue.taskcluster.net/v1/task/ff45gAeUSMmiMPeyn1GlEA/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip into memory
14:35:43 INFO - Http code: 200
14:35:43 INFO - Content-Encoding: None
14:35:43 INFO - Content-Type: application/zip
14:35:43 INFO - via: 1.1 c0740de9f16cf32ffedfd2f9806b8483.cloudfront.net (CloudFront)
14:35:43 INFO - x-amz-cf-id: F1mDAFOtnHVf0Lt9A8CmuiBY08NgUO-WBpe3wZFbgzzrz7uXLRGPvQ==
14:35:43 INFO - x-amz-version-id: 8k6SJ54LVCj379Vw1k6KC1D1TiyzS.4V
14:35:43 INFO - x-cache: Hit from cloudfront
14:35:43 INFO - Expected file size: 14414067
14:35:43 INFO - Obtained file size: 14414067
14:35:50 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')]}
14:35:50 INFO - trying https://queue.taskcluster.net/v1/task/ff45gAeUSMmiMPeyn1GlEA/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
14:35:50 INFO - Downloading https://queue.taskcluster.net/v1/task/ff45gAeUSMmiMPeyn1GlEA/artifacts/public/build/firefox-52.0a1.en-US.win32.zip to C:\slave\test\build\firefox-52.0a1.en-US.win32.zip
14:35:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ff45gAeUSMmiMPeyn1GlEA/artifacts/public/build/firefox-52.0a1.en-US.win32.zip', 'file_name': 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.zip'}, attempt #1
14:35:53 INFO - Downloaded 53543125 bytes.
14:35:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ff45gAeUSMmiMPeyn1GlEA/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
14:35:53 INFO - mkdir: C:\slave\test\properties
14:35:53 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url
14:35:53 INFO - Writing to file C:\slave\test\properties\build_url
14:35:53 INFO - Contents:
14:35:53 INFO - build_url:https://queue.taskcluster.net/v1/task/ff45gAeUSMmiMPeyn1GlEA/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
14:35:53 INFO - Running post-action listener: _resource_record_post_action
14:35:53 INFO - Running post-action listener: set_extra_try_arguments
14:35:53 INFO - [mozharness: 2016-09-28 21:35:53.869000Z] Finished download-and-extract step (success)
14:35:53 INFO - [mozharness: 2016-09-28 21:35:53.869000Z] Running populate-webroot step.
14:35:53 INFO - Running pre-action listener: _resource_record_pre_action
14:35:53 INFO - Running main action method: populate_webroot
14:35:53 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']},
14:35:53 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']},
14:35:53 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome',
14:35:53 INFO - u'--tppagecycles',
14:35:53 INFO - u'7'],
14:35:53 INFO - u'tests': [u'tsvgm']}},
14:35:53 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'],
14:35:53 INFO - u'tests': [u'tresize', u'tcanvasmark']},
14:35:53 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']},
14:35:53 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'],
14:35:53 INFO - u'tests': [u'dromaeo_css', u'kraken']},
14:35:53 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']},
14:35:53 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
14:35:53 INFO - u'talos_options': [u'--disable-e10s'],
14:35:53 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
14:35:53 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
14:35:53 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
14:35:53 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
14:35:53 INFO - u'talos_options': [u'--disable-e10s'],
14:35:53 INFO - u'tests': [u'damp', u'tps']},
14:35:53 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
14:35:53 INFO - u'tests': [u'damp', u'tps']},
14:35:53 INFO - u'g3': {u'talos_options': [u'--disable-e10s'],
14:35:53 INFO - u'tests': [u'dromaeo_dom']},
14:35:53 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']},
14:35:53 INFO - u'g4': {u'talos_options': [u'--disable-e10s'],
14:35:53 INFO - u'tests': [u'basic_compositor_video']},
14:35:53 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']},
14:35:53 INFO - u'other': {u'talos_options': [u'--disable-e10s'],
14:35:53 INFO - u'tests': [u'a11yr',
14:35:53 INFO - u'ts_paint',
14:35:53 INFO - u'tpaint',
14:35:53 INFO - u'sessionrestore',
14:35:53 INFO - u'sessionrestore_no_auto_restore',
14:35:53 INFO - u'tabpaint']},
14:35:53 INFO - u'other-e10s': {u'tests': [u'a11yr',
14:35:53 INFO - u'ts_paint',
14:35:53 INFO - u'tpaint',
14:35:53 INFO - u'sessionrestore',
14:35:53 INFO - u'sessionrestore_no_auto_restore',
14:35:53 INFO - u'tabpaint']},
14:35:53 INFO - u'other-e10s_l64': {u'tests': [u'a11yr',
14:35:53 INFO - u'ts_paint',
14:35:53 INFO - u'tpaint',
14:35:53 INFO - u'sessionrestore',
14:35:53 INFO - u'sessionrestore_no_auto_restore',
14:35:53 INFO - u'tabpaint']},
14:35:53 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr',
14:35:53 INFO - u'ts_paint',
14:35:53 INFO - u'tpaint',
14:35:53 INFO - u'sessionrestore',
14:35:53 INFO - u'sessionrestore_no_auto_restore',
14:35:53 INFO - u'tabpaint']},
14:35:53 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'],
14:35:53 INFO - u'tests': [u'a11yr',
14:35:53 INFO - u'ts_paint',
14:35:53 INFO - u'tpaint',
14:35:53 INFO - u'sessionrestore',
14:35:53 INFO - u'sessionrestore_no_auto_restore',
14:35:53 INFO - u'tabpaint']},
14:35:53 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'],
14:35:53 INFO - u'tests': [u'a11yr',
14:35:53 INFO - u'ts_paint',
14:35:53 INFO - u'tpaint',
14:35:53 INFO - u'sessionrestore',
14:35:53 INFO - u'sessionrestore_no_auto_restore',
14:35:53 INFO - u'tabpaint']},
14:35:53 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'],
14:35:53 INFO - u'tests': [u'tsvgx',
14:35:53 INFO - u'tsvgr_opacity',
14:35:53 INFO - u'tart',
14:35:53 INFO - u'tscrollx',
14:35:53 INFO - u'cart']},
14:35:53 INFO - u'svgr-e10s': {u'tests': [u'tsvgx',
14:35:53 INFO - u'tsvgr_opacity',
14:35:53 INFO - u'tart',
14:35:53 INFO - u'tscrollx',
14:35:53 INFO - u'cart']},
14:35:53 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
14:35:53 INFO - u'talos_options': [u'--disable-e10s'],
14:35:53 INFO - u'tests': [u'tp5o']},
14:35:53 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
14:35:53 INFO - u'tests': [u'tp5o']},
14:35:53 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
14:35:53 INFO - u'talos_options': [u'--disable-e10s',
14:35:53 INFO - u'--xperf_path',
14:35:53 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
14:35:53 INFO - u'tests': [u'tp5n']},
14:35:53 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
14:35:53 INFO - u'talos_options': [u'--xperf_path',
14:35:53 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
14:35:53 INFO - u'tests': [u'tp5n']}},
14:35:53 INFO - u'talos.zip': {u'path': u'',
14:35:53 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}}
14:35:53 INFO - Running post-action listener: _resource_record_post_action
14:35:53 INFO - [mozharness: 2016-09-28 21:35:53.930000Z] Finished populate-webroot step (success)
14:35:53 INFO - [mozharness: 2016-09-28 21:35:53.930000Z] Running create-virtualenv step.
14:35:53 INFO - Running pre-action listener: _resource_record_pre_action
14:35:53 INFO - Running main action method: create_virtualenv
14:35:53 INFO - Creating virtualenv C:\slave\test\build/venv
14:35:53 INFO - mkdir: C:\slave\test\build\venv\Scripts
14:35:54 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll
14:35:54 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'!
14:35:54 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
14:35:54 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv
14:36:01 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe
14:36:05 INFO - Installing distribute......................................................................................................................................................................................done.
14:36:07 INFO - Return code: 0
14:36:07 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version']
14:36:07 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version
14:36:08 INFO - Reading from file tmpfile_stdout
14:36:08 INFO - Output received:
14:36:08 INFO - pip 0.8.2 from c:\slave\test\build\venv\lib\site-packages\pip-0.8.2-py2.7.egg (python 2.7)
14:36:08 INFO - Using _rmtree_windows ...
14:36:08 INFO - Using _rmtree_windows ...
14:36:08 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv
14:36:08 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')]}
14:36:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
14:36:08 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')]}
14:36:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
14:36:08 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 0x0186E640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01929E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0182D8F8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01944E90>, '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 0x012EA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F82520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-117', 'USERDOMAIN': 'T-W732-IX-117', '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~1.T-W\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W732-IX-117\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W732-IX-117', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-117', '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.T-W732-IX-117\\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.T-W732-IX-117', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
14:36:08 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
14:36:08 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
14:36:08 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
14:36:08 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W732-IX-117\\AppData\\Roaming',
14:36:08 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
14:36:08 INFO - 'COMPUTERNAME': 'T-W732-IX-117',
14:36:08 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
14:36:08 INFO - 'CYGWINBASE': 'C:\\cygwin',
14:36:08 INFO - 'DCLOCATION': 'SCL3',
14:36:08 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
14:36:08 INFO - 'FP_NO_HOST_CHECK': 'NO',
14:36:08 INFO - 'HOMEDRIVE': 'C:',
14:36:08 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W732-IX-117',
14:36:08 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
14:36:08 INFO - 'KTS_VERSION': '1.19c',
14:36:08 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W732-IX-117\\AppData\\Local',
14:36:08 INFO - 'LOGONSERVER': '\\\\T-W732-IX-117',
14:36:08 INFO - 'MONDIR': 'C:\\Monitor_config\\',
14:36:08 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
14:36:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
14:36:08 INFO - 'MOZ_NO_REMOTE': '1',
14:36:08 INFO - 'NO_EM_RESTART': '1',
14:36:08 INFO - 'NUMBER_OF_PROCESSORS': '8',
14:36:08 INFO - 'OS': 'Windows_NT',
14:36:08 INFO - 'OURDRIVE': 'C:',
14:36:08 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;',
14:36:08 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
14:36:08 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
14:36:08 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
14:36:08 INFO - 'PROCESSOR_LEVEL': '6',
14:36:08 INFO - 'PROCESSOR_REVISION': '1e05',
14:36:08 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
14:36:08 INFO - 'PROGRAMFILES': 'C:\\Program Files',
14:36:08 INFO - 'PROMPT': '$P$G',
14:36:08 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
14:36:08 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
14:36:08 INFO - 'PUBLIC': 'C:\\Users\\Public',
14:36:08 INFO - 'PWD': 'C:\\slave\\test',
14:36:08 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
14:36:08 INFO - 'SLAVEDIR': 'C:\\slave\\',
14:36:08 INFO - 'SYSTEMDRIVE': 'C:',
14:36:08 INFO - 'SYSTEMROOT': 'C:\\windows',
14:36:08 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp',
14:36:08 INFO - 'TEST1': 'testie',
14:36:08 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp',
14:36:08 INFO - 'USERDOMAIN': 'T-W732-IX-117',
14:36:08 INFO - 'USERNAME': 'cltbld',
14:36:08 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W732-IX-117',
14:36:08 INFO - 'WINDIR': 'C:\\windows',
14:36:08 INFO - 'WINDOWS_TRACING_FLAGS': '3',
14:36:08 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
14:36:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
14:36:09 INFO - Ignoring indexes: http://pypi.python.org/simple/
14:36:09 INFO - Downloading/unpacking pip>=1.5
14:36:13 INFO - Running setup.py egg_info for package pip
14:36:13 INFO - warning: no files found matching 'pip\cacert.pem'
14:36:13 INFO - warning: no files found matching '*.html' under directory 'docs'
14:36:13 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
14:36:13 INFO - no previously-included directories found matching 'docs\_build\_sources'
14:36:13 INFO - Installing collected packages: pip
14:36:13 INFO - Found existing installation: pip 0.8.2
14:36:13 INFO - Uninstalling pip:
14:36:13 INFO - Successfully uninstalled pip
14:36:13 INFO - Running setup.py install for pip
14:36:13 INFO - warning: no files found matching 'pip\cacert.pem'
14:36:13 INFO - warning: no files found matching '*.html' under directory 'docs'
14:36:13 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
14:36:13 INFO - no previously-included directories found matching 'docs\_build\_sources'
14:36:13 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts
14:36:13 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts
14:36:13 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts
14:36:13 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts
14:36:13 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts
14:36:13 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts
14:36:13 INFO - Successfully installed pip
14:36:13 INFO - Cleaning up...
14:36:13 INFO - Return code: 0
14:36:13 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv
14:36:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
14:36:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
14:36:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
14:36:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
14:36:13 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 0x0186E640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01929E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0182D8F8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01944E90>, '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 0x012EA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F82520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-117', 'USERDOMAIN': 'T-W732-IX-117', '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~1.T-W\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W732-IX-117\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W732-IX-117', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-117', '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.T-W732-IX-117\\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.T-W732-IX-117', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
14:36:13 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
14:36:13 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
14:36:13 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
14:36:13 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W732-IX-117\\AppData\\Roaming',
14:36:13 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
14:36:13 INFO - 'COMPUTERNAME': 'T-W732-IX-117',
14:36:13 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
14:36:13 INFO - 'CYGWINBASE': 'C:\\cygwin',
14:36:13 INFO - 'DCLOCATION': 'SCL3',
14:36:13 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
14:36:13 INFO - 'FP_NO_HOST_CHECK': 'NO',
14:36:13 INFO - 'HOMEDRIVE': 'C:',
14:36:13 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W732-IX-117',
14:36:13 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
14:36:13 INFO - 'KTS_VERSION': '1.19c',
14:36:13 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W732-IX-117\\AppData\\Local',
14:36:13 INFO - 'LOGONSERVER': '\\\\T-W732-IX-117',
14:36:13 INFO - 'MONDIR': 'C:\\Monitor_config\\',
14:36:13 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
14:36:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
14:36:13 INFO - 'MOZ_NO_REMOTE': '1',
14:36:13 INFO - 'NO_EM_RESTART': '1',
14:36:13 INFO - 'NUMBER_OF_PROCESSORS': '8',
14:36:13 INFO - 'OS': 'Windows_NT',
14:36:13 INFO - 'OURDRIVE': 'C:',
14:36:13 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;',
14:36:13 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
14:36:13 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
14:36:13 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
14:36:13 INFO - 'PROCESSOR_LEVEL': '6',
14:36:13 INFO - 'PROCESSOR_REVISION': '1e05',
14:36:13 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
14:36:13 INFO - 'PROGRAMFILES': 'C:\\Program Files',
14:36:13 INFO - 'PROMPT': '$P$G',
14:36:13 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
14:36:13 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
14:36:13 INFO - 'PUBLIC': 'C:\\Users\\Public',
14:36:13 INFO - 'PWD': 'C:\\slave\\test',
14:36:13 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
14:36:13 INFO - 'SLAVEDIR': 'C:\\slave\\',
14:36:13 INFO - 'SYSTEMDRIVE': 'C:',
14:36:13 INFO - 'SYSTEMROOT': 'C:\\windows',
14:36:13 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp',
14:36:13 INFO - 'TEST1': 'testie',
14:36:13 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp',
14:36:13 INFO - 'USERDOMAIN': 'T-W732-IX-117',
14:36:13 INFO - 'USERNAME': 'cltbld',
14:36:13 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W732-IX-117',
14:36:13 INFO - 'WINDIR': 'C:\\windows',
14:36:13 INFO - 'WINDOWS_TRACING_FLAGS': '3',
14:36:13 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
14:36:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
14:36:14 INFO - Ignoring indexes: https://pypi.python.org/simple/
14:36:14 INFO - Downloading/unpacking psutil>=3.1.1
14:36: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
14:36: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
14:36:15 INFO - Installing collected packages: psutil
14:36:15 INFO - Successfully installed psutil
14:36:15 INFO - Cleaning up...
14:36:15 INFO - Return code: 0
14:36:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv
14:36:15 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')]}
14:36:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
14:36:15 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')]}
14:36:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
14:36:15 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 0x0186E640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01929E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0182D8F8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01944E90>, '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 0x012EA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F82520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-117', 'USERDOMAIN': 'T-W732-IX-117', '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~1.T-W\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W732-IX-117\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W732-IX-117', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-117', '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.T-W732-IX-117\\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.T-W732-IX-117', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
14:36:15 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
14:36:15 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
14:36:15 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
14:36:15 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W732-IX-117\\AppData\\Roaming',
14:36:15 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
14:36:15 INFO - 'COMPUTERNAME': 'T-W732-IX-117',
14:36:15 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
14:36:15 INFO - 'CYGWINBASE': 'C:\\cygwin',
14:36:15 INFO - 'DCLOCATION': 'SCL3',
14:36:15 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
14:36:15 INFO - 'FP_NO_HOST_CHECK': 'NO',
14:36:15 INFO - 'HOMEDRIVE': 'C:',
14:36:15 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W732-IX-117',
14:36:15 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
14:36:15 INFO - 'KTS_VERSION': '1.19c',
14:36:15 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W732-IX-117\\AppData\\Local',
14:36:15 INFO - 'LOGONSERVER': '\\\\T-W732-IX-117',
14:36:15 INFO - 'MONDIR': 'C:\\Monitor_config\\',
14:36:15 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
14:36:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
14:36:15 INFO - 'MOZ_NO_REMOTE': '1',
14:36:15 INFO - 'NO_EM_RESTART': '1',
14:36:15 INFO - 'NUMBER_OF_PROCESSORS': '8',
14:36:15 INFO - 'OS': 'Windows_NT',
14:36:15 INFO - 'OURDRIVE': 'C:',
14:36:15 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;',
14:36:15 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
14:36:15 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
14:36:15 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
14:36:15 INFO - 'PROCESSOR_LEVEL': '6',
14:36:15 INFO - 'PROCESSOR_REVISION': '1e05',
14:36:15 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
14:36:15 INFO - 'PROGRAMFILES': 'C:\\Program Files',
14:36:15 INFO - 'PROMPT': '$P$G',
14:36:15 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
14:36:15 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
14:36:15 INFO - 'PUBLIC': 'C:\\Users\\Public',
14:36:15 INFO - 'PWD': 'C:\\slave\\test',
14:36:15 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
14:36:15 INFO - 'SLAVEDIR': 'C:\\slave\\',
14:36:15 INFO - 'SYSTEMDRIVE': 'C:',
14:36:15 INFO - 'SYSTEMROOT': 'C:\\windows',
14:36:15 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp',
14:36:15 INFO - 'TEST1': 'testie',
14:36:15 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp',
14:36:15 INFO - 'USERDOMAIN': 'T-W732-IX-117',
14:36:15 INFO - 'USERNAME': 'cltbld',
14:36:15 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W732-IX-117',
14:36:15 INFO - 'WINDIR': 'C:\\windows',
14:36:15 INFO - 'WINDOWS_TRACING_FLAGS': '3',
14:36:15 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
14:36:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
14:36:16 INFO - Ignoring indexes: https://pypi.python.org/simple/
14:36:16 INFO - Downloading/unpacking mozsystemmonitor==0.3
14:36:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
14:36:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
14:36:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz
14:36:17 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor
14:36:17 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)
14:36:17 INFO - Installing collected packages: mozsystemmonitor
14:36:17 INFO - Running setup.py install for mozsystemmonitor
14:36:17 INFO - Successfully installed mozsystemmonitor
14:36:17 INFO - Cleaning up...
14:36:17 INFO - Return code: 0
14:36:17 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv
14:36:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
14:36:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
14:36:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
14:36:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
14:36:17 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x0186E640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01929E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0182D8F8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01944E90>, '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 0x012EA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F82520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-117', 'USERDOMAIN': 'T-W732-IX-117', '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~1.T-W\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W732-IX-117\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W732-IX-117', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-117', '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.T-W732-IX-117\\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.T-W732-IX-117', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
14:36:17 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in C:\slave\test\build
14:36:17 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1
14:36:17 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
14:36:17 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W732-IX-117\\AppData\\Roaming',
14:36:17 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
14:36:17 INFO - 'COMPUTERNAME': 'T-W732-IX-117',
14:36:17 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
14:36:17 INFO - 'CYGWINBASE': 'C:\\cygwin',
14:36:17 INFO - 'DCLOCATION': 'SCL3',
14:36:17 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
14:36:17 INFO - 'FP_NO_HOST_CHECK': 'NO',
14:36:17 INFO - 'HOMEDRIVE': 'C:',
14:36:17 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W732-IX-117',
14:36:17 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
14:36:17 INFO - 'KTS_VERSION': '1.19c',
14:36:17 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W732-IX-117\\AppData\\Local',
14:36:17 INFO - 'LOGONSERVER': '\\\\T-W732-IX-117',
14:36:17 INFO - 'MONDIR': 'C:\\Monitor_config\\',
14:36:17 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
14:36:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
14:36:17 INFO - 'MOZ_NO_REMOTE': '1',
14:36:17 INFO - 'NO_EM_RESTART': '1',
14:36:17 INFO - 'NUMBER_OF_PROCESSORS': '8',
14:36:17 INFO - 'OS': 'Windows_NT',
14:36:17 INFO - 'OURDRIVE': 'C:',
14:36:17 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
14:36:17 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
14:36:17 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
14:36:17 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
14:36:17 INFO - 'PROCESSOR_LEVEL': '6',
14:36:17 INFO - 'PROCESSOR_REVISION': '1e05',
14:36:17 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
14:36:17 INFO - 'PROGRAMFILES': 'C:\\Program Files',
14:36:17 INFO - 'PROMPT': '$P$G',
14:36:17 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
14:36:17 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
14:36:17 INFO - 'PUBLIC': 'C:\\Users\\Public',
14:36:17 INFO - 'PWD': 'C:\\slave\\test',
14:36:17 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
14:36:17 INFO - 'SLAVEDIR': 'C:\\slave\\',
14:36:17 INFO - 'SYSTEMDRIVE': 'C:',
14:36:17 INFO - 'SYSTEMROOT': 'C:\\windows',
14:36:17 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp',
14:36:17 INFO - 'TEST1': 'testie',
14:36:17 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp',
14:36:17 INFO - 'USERDOMAIN': 'T-W732-IX-117',
14:36:17 INFO - 'USERNAME': 'cltbld',
14:36:17 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W732-IX-117',
14:36:17 INFO - 'WINDIR': 'C:\\windows',
14:36:17 INFO - 'WINDOWS_TRACING_FLAGS': '3',
14:36:17 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
14:36:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
14:36:17 INFO - Ignoring indexes: https://pypi.python.org/simple/
14:36:17 INFO - Downloading/unpacking jsonschema==2.5.1
14:36:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
14:36:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
14:36:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl
14:36:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1)
14:36:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
14:36:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
14:36:19 INFO - Downloading functools32-3.2.3-2.tar.gz
14:36:19 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32
14:36:19 INFO - warning: no files found matching '*.txt'
14:36:19 INFO - no previously-included directories found matching 'build'
14:36:19 INFO - no previously-included directories found matching 'dist'
14:36:19 INFO - no previously-included directories found matching '.git*'
14:36:19 INFO - Installing collected packages: jsonschema, functools32
14:36:19 INFO - Running setup.py install for functools32
14:36:19 INFO - warning: no files found matching '*.txt'
14:36:19 INFO - no previously-included directories found matching 'build'
14:36:19 INFO - no previously-included directories found matching 'dist'
14:36:19 INFO - no previously-included directories found matching '.git*'
14:36:19 INFO - Successfully installed jsonschema functools32
14:36:19 INFO - Cleaning up...
14:36:19 INFO - Return code: 0
14:36:19 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv
14:36:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
14:36:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
14:36:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
14:36:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
14:36:19 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 0x0186E640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01929E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0182D8F8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01944E90>, '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 0x012EA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F82520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-117', 'USERDOMAIN': 'T-W732-IX-117', '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~1.T-W\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W732-IX-117\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W732-IX-117', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-117', '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.T-W732-IX-117\\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.T-W732-IX-117', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
14:36:19 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
14:36:19 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
14:36:19 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
14:36:19 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W732-IX-117\\AppData\\Roaming',
14:36:19 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
14:36:19 INFO - 'COMPUTERNAME': 'T-W732-IX-117',
14:36:19 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
14:36:19 INFO - 'CYGWINBASE': 'C:\\cygwin',
14:36:19 INFO - 'DCLOCATION': 'SCL3',
14:36:19 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
14:36:19 INFO - 'FP_NO_HOST_CHECK': 'NO',
14:36:19 INFO - 'HOMEDRIVE': 'C:',
14:36:19 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W732-IX-117',
14:36:19 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
14:36:19 INFO - 'KTS_VERSION': '1.19c',
14:36:19 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W732-IX-117\\AppData\\Local',
14:36:19 INFO - 'LOGONSERVER': '\\\\T-W732-IX-117',
14:36:19 INFO - 'MONDIR': 'C:\\Monitor_config\\',
14:36:19 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
14:36:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
14:36:19 INFO - 'MOZ_NO_REMOTE': '1',
14:36:19 INFO - 'NO_EM_RESTART': '1',
14:36:19 INFO - 'NUMBER_OF_PROCESSORS': '8',
14:36:19 INFO - 'OS': 'Windows_NT',
14:36:19 INFO - 'OURDRIVE': 'C:',
14:36:19 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
14:36:19 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
14:36:19 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
14:36:19 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
14:36:19 INFO - 'PROCESSOR_LEVEL': '6',
14:36:19 INFO - 'PROCESSOR_REVISION': '1e05',
14:36:19 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
14:36:19 INFO - 'PROGRAMFILES': 'C:\\Program Files',
14:36:19 INFO - 'PROMPT': '$P$G',
14:36:19 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
14:36:19 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
14:36:19 INFO - 'PUBLIC': 'C:\\Users\\Public',
14:36:19 INFO - 'PWD': 'C:\\slave\\test',
14:36:19 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
14:36:19 INFO - 'SLAVEDIR': 'C:\\slave\\',
14:36:19 INFO - 'SYSTEMDRIVE': 'C:',
14:36:19 INFO - 'SYSTEMROOT': 'C:\\windows',
14:36:19 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp',
14:36:19 INFO - 'TEST1': 'testie',
14:36:19 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp',
14:36:19 INFO - 'USERDOMAIN': 'T-W732-IX-117',
14:36:19 INFO - 'USERNAME': 'cltbld',
14:36:19 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W732-IX-117',
14:36:19 INFO - 'WINDIR': 'C:\\windows',
14:36:19 INFO - 'WINDOWS_TRACING_FLAGS': '3',
14:36:19 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
14:36:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
14:36:20 INFO - Ignoring indexes: https://pypi.python.org/simple/
14:36:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages
14:36:20 INFO - Cleaning up...
14:36:20 INFO - Return code: 0
14:36:20 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv
14:36: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')]}
14:36:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
14:36: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')]}
14:36:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
14:36: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', '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 0x0186E640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01929E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0182D8F8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01944E90>, '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 0x012EA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F82520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-117', 'USERDOMAIN': 'T-W732-IX-117', '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~1.T-W\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W732-IX-117\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W732-IX-117', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-117', '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.T-W732-IX-117\\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.T-W732-IX-117', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
14:36:20 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
14:36:20 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
14:36:20 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
14:36:20 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W732-IX-117\\AppData\\Roaming',
14:36:20 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
14:36:20 INFO - 'COMPUTERNAME': 'T-W732-IX-117',
14:36:20 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
14:36:20 INFO - 'CYGWINBASE': 'C:\\cygwin',
14:36:20 INFO - 'DCLOCATION': 'SCL3',
14:36:20 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
14:36:20 INFO - 'FP_NO_HOST_CHECK': 'NO',
14:36:20 INFO - 'HOMEDRIVE': 'C:',
14:36:20 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W732-IX-117',
14:36:20 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
14:36:20 INFO - 'KTS_VERSION': '1.19c',
14:36:20 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W732-IX-117\\AppData\\Local',
14:36:20 INFO - 'LOGONSERVER': '\\\\T-W732-IX-117',
14:36:20 INFO - 'MONDIR': 'C:\\Monitor_config\\',
14:36:20 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
14:36:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
14:36:20 INFO - 'MOZ_NO_REMOTE': '1',
14:36:20 INFO - 'NO_EM_RESTART': '1',
14:36:20 INFO - 'NUMBER_OF_PROCESSORS': '8',
14:36:20 INFO - 'OS': 'Windows_NT',
14:36:20 INFO - 'OURDRIVE': 'C:',
14:36:20 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
14:36:20 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
14:36:20 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
14:36:20 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
14:36:20 INFO - 'PROCESSOR_LEVEL': '6',
14:36:20 INFO - 'PROCESSOR_REVISION': '1e05',
14:36:20 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
14:36:20 INFO - 'PROGRAMFILES': 'C:\\Program Files',
14:36:20 INFO - 'PROMPT': '$P$G',
14:36:20 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
14:36:20 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
14:36:20 INFO - 'PUBLIC': 'C:\\Users\\Public',
14:36:20 INFO - 'PWD': 'C:\\slave\\test',
14:36:20 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
14:36:20 INFO - 'SLAVEDIR': 'C:\\slave\\',
14:36:20 INFO - 'SYSTEMDRIVE': 'C:',
14:36:20 INFO - 'SYSTEMROOT': 'C:\\windows',
14:36:20 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp',
14:36:20 INFO - 'TEST1': 'testie',
14:36:20 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp',
14:36:20 INFO - 'USERDOMAIN': 'T-W732-IX-117',
14:36:20 INFO - 'USERNAME': 'cltbld',
14:36:20 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W732-IX-117',
14:36:20 INFO - 'WINDIR': 'C:\\windows',
14:36:20 INFO - 'WINDOWS_TRACING_FLAGS': '3',
14:36:20 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
14:36:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
14:36:20 INFO - Ignoring indexes: https://pypi.python.org/simple/
14:36:20 INFO - Downloading/unpacking blobuploader==1.2.4
14:36:20 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
14:36:20 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
14:36:21 INFO - Downloading blobuploader-1.2.4.tar.gz
14:36:21 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader
14:36:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4)
14:36: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
14:36: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
14:36:21 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests
14:36:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4)
14:36: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
14:36: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
14:36:22 INFO - Downloading docopt-0.6.1.tar.gz
14:36:23 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt
14:36:23 INFO - Installing collected packages: blobuploader, requests, docopt
14:36:23 INFO - Running setup.py install for blobuploader
14:36:23 INFO - Running setup.py install for requests
14:36:24 INFO - Running setup.py install for docopt
14:36:24 INFO - Successfully installed blobuploader requests docopt
14:36:24 INFO - Cleaning up...
14:36:25 INFO - Return code: 0
14:36:25 INFO - Installing None into virtualenv C:\slave\test\build/venv
14:36:25 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')]}
14:36:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
14:36:25 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')]}
14:36:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
14:36:25 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 0x0186E640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01929E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0182D8F8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01944E90>, '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 0x012EA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F82520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-117', 'USERDOMAIN': 'T-W732-IX-117', '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~1.T-W\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W732-IX-117\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W732-IX-117', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-117', '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.T-W732-IX-117\\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.T-W732-IX-117', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
14:36:25 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
14:36:25 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
14:36:25 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
14:36:25 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W732-IX-117\\AppData\\Roaming',
14:36:25 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
14:36:25 INFO - 'COMPUTERNAME': 'T-W732-IX-117',
14:36:25 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
14:36:25 INFO - 'CYGWINBASE': 'C:\\cygwin',
14:36:25 INFO - 'DCLOCATION': 'SCL3',
14:36:25 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
14:36:25 INFO - 'FP_NO_HOST_CHECK': 'NO',
14:36:25 INFO - 'HOMEDRIVE': 'C:',
14:36:25 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W732-IX-117',
14:36:25 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
14:36:25 INFO - 'KTS_VERSION': '1.19c',
14:36:25 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W732-IX-117\\AppData\\Local',
14:36:25 INFO - 'LOGONSERVER': '\\\\T-W732-IX-117',
14:36:25 INFO - 'MONDIR': 'C:\\Monitor_config\\',
14:36:25 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
14:36:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
14:36:25 INFO - 'MOZ_NO_REMOTE': '1',
14:36:25 INFO - 'NO_EM_RESTART': '1',
14:36:25 INFO - 'NUMBER_OF_PROCESSORS': '8',
14:36:25 INFO - 'OS': 'Windows_NT',
14:36:25 INFO - 'OURDRIVE': 'C:',
14:36:25 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;',
14:36:25 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
14:36:25 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
14:36:25 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
14:36:25 INFO - 'PROCESSOR_LEVEL': '6',
14:36:25 INFO - 'PROCESSOR_REVISION': '1e05',
14:36:25 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
14:36:25 INFO - 'PROGRAMFILES': 'C:\\Program Files',
14:36:25 INFO - 'PROMPT': '$P$G',
14:36:25 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
14:36:25 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
14:36:25 INFO - 'PUBLIC': 'C:\\Users\\Public',
14:36:25 INFO - 'PWD': 'C:\\slave\\test',
14:36:25 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
14:36:25 INFO - 'SLAVEDIR': 'C:\\slave\\',
14:36:25 INFO - 'SYSTEMDRIVE': 'C:',
14:36:25 INFO - 'SYSTEMROOT': 'C:\\windows',
14:36:25 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp',
14:36:25 INFO - 'TEST1': 'testie',
14:36:25 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp',
14:36:25 INFO - 'USERDOMAIN': 'T-W732-IX-117',
14:36:25 INFO - 'USERNAME': 'cltbld',
14:36:25 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W732-IX-117',
14:36:25 INFO - 'WINDIR': 'C:\\windows',
14:36:25 INFO - 'WINDOWS_TRACING_FLAGS': '3',
14:36:25 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
14:36:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
14:36:26 INFO - Ignoring indexes: https://pypi.python.org/simple/
14:36:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
14:36:26 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-cb8lra-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
14:36:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
14:36:26 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-56m_4u-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
14:36:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
14:36:26 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-lhgrfn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
14:36:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
14:36:26 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-qird91-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
14:36:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
14:36:26 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-zxlyok-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
14:36:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
14:36:26 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-9vafhu-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
14:36:27 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
14:36:27 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-tbemez-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
14:36:27 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
14:36:27 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-7rfyy7-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
14:36:27 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
14:36:27 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-nljsn_-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
14:36:27 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
14:36:27 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-jx6sg2-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
14:36:27 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
14:36:27 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-3p5xqh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
14:36:27 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
14:36:27 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-disels-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
14:36:27 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
14:36:27 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-snnkqv-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
14:36:28 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
14:36:28 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-fj6jeo-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
14:36:28 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
14:36:28 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-d3zmce-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
14:36:28 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
14:36:28 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-ruktyf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
14:36:28 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
14:36:28 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-mnbg0o-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
14:36:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
14:36:28 INFO - Running setup.py install for manifestparser
14:36:29 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts
14:36:29 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts
14:36:29 INFO - Running setup.py install for mozcrash
14:36:29 INFO - Running setup.py install for mozdebug
14:36:30 INFO - Running setup.py install for mozdevice
14:36:30 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts
14:36:30 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts
14:36:30 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts
14:36:30 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts
14:36:30 INFO - Running setup.py install for mozfile
14:36:31 INFO - Running setup.py install for mozhttpd
14:36:31 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts
14:36:31 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts
14:36:31 INFO - Running setup.py install for mozinfo
14:36:31 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts
14:36:31 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts
14:36:31 INFO - Running setup.py install for mozInstall
14:36:32 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts
14:36:32 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts
14:36:32 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts
14:36:32 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts
14:36:32 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts
14:36:32 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts
14:36:32 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts
14:36:32 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts
14:36:32 INFO - Running setup.py install for mozleak
14:36:32 INFO - Running setup.py install for mozlog
14:36:32 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts
14:36:32 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts
14:36:33 INFO - Running setup.py install for moznetwork
14:36:33 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts
14:36:33 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts
14:36:33 INFO - Running setup.py install for mozprocess
14:36:34 INFO - Running setup.py install for mozprofile
14:36:34 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts
14:36:34 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts
14:36:34 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts
14:36:34 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts
14:36:34 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts
14:36:34 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts
14:36:35 INFO - Running setup.py install for mozrunner
14:36:35 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts
14:36:35 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts
14:36:35 INFO - Running setup.py install for mozscreenshot
14:36:36 INFO - Running setup.py install for moztest
14:36:36 INFO - Running setup.py install for mozversion
14:36:36 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts
14:36:36 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts
14:36:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion
14:36:36 INFO - Cleaning up...
14:36:36 INFO - Return code: 0
14:36:36 INFO - Installing None into virtualenv C:\slave\test\build/venv
14:36:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
14:36:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
14:36:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
14:36:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
14:36:36 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 0x0186E640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01929E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0182D8F8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01944E90>, '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 0x012EA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F82520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-117', 'USERDOMAIN': 'T-W732-IX-117', '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~1.T-W\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W732-IX-117\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W732-IX-117', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-117', '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.T-W732-IX-117\\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.T-W732-IX-117', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
14:36:37 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
14:36:37 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
14:36:37 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
14:36:37 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W732-IX-117\\AppData\\Roaming',
14:36:37 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
14:36:37 INFO - 'COMPUTERNAME': 'T-W732-IX-117',
14:36:37 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
14:36:37 INFO - 'CYGWINBASE': 'C:\\cygwin',
14:36:37 INFO - 'DCLOCATION': 'SCL3',
14:36:37 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
14:36:37 INFO - 'FP_NO_HOST_CHECK': 'NO',
14:36:37 INFO - 'HOMEDRIVE': 'C:',
14:36:37 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W732-IX-117',
14:36:37 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
14:36:37 INFO - 'KTS_VERSION': '1.19c',
14:36:37 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W732-IX-117\\AppData\\Local',
14:36:37 INFO - 'LOGONSERVER': '\\\\T-W732-IX-117',
14:36:37 INFO - 'MONDIR': 'C:\\Monitor_config\\',
14:36:37 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
14:36:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
14:36:37 INFO - 'MOZ_NO_REMOTE': '1',
14:36:37 INFO - 'NO_EM_RESTART': '1',
14:36:37 INFO - 'NUMBER_OF_PROCESSORS': '8',
14:36:37 INFO - 'OS': 'Windows_NT',
14:36:37 INFO - 'OURDRIVE': 'C:',
14:36:37 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;',
14:36:37 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
14:36:37 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
14:36:37 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
14:36:37 INFO - 'PROCESSOR_LEVEL': '6',
14:36:37 INFO - 'PROCESSOR_REVISION': '1e05',
14:36:37 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
14:36:37 INFO - 'PROGRAMFILES': 'C:\\Program Files',
14:36:37 INFO - 'PROMPT': '$P$G',
14:36:37 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
14:36:37 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
14:36:37 INFO - 'PUBLIC': 'C:\\Users\\Public',
14:36:37 INFO - 'PWD': 'C:\\slave\\test',
14:36:37 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
14:36:37 INFO - 'SLAVEDIR': 'C:\\slave\\',
14:36:37 INFO - 'SYSTEMDRIVE': 'C:',
14:36:37 INFO - 'SYSTEMROOT': 'C:\\windows',
14:36:37 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp',
14:36:37 INFO - 'TEST1': 'testie',
14:36:37 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp',
14:36:37 INFO - 'USERDOMAIN': 'T-W732-IX-117',
14:36:37 INFO - 'USERNAME': 'cltbld',
14:36:37 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W732-IX-117',
14:36:37 INFO - 'WINDIR': 'C:\\windows',
14:36:37 INFO - 'WINDOWS_TRACING_FLAGS': '3',
14:36:37 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
14:36:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
14:36:37 INFO - Ignoring indexes: https://pypi.python.org/simple/
14:36:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
14:36:37 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-9es6ag-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
14:36:37 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))
14:36:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
14:36:37 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-p1dz1f-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
14:36:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///C:/slave/test/build/tests/mozbase/mozcrash in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2))
14:36:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
14:36:37 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-rsghd2-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
14:36:37 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))
14:36:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
14:36:37 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-e9r0wv-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
14:36:37 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))
14:36:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
14:36:37 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-1lg1uo-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
14:36:38 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))
14:36:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
14:36:38 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-twbvac-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
14:36:38 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))
14:36:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
14:36:38 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-43j3dd-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
14:36:38 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))
14:36:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
14:36:38 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-39rmnl-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
14:36:38 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))
14:36:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
14:36:38 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-x8pvth-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
14:36:38 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))
14:36:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
14:36:38 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-ickiej-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
14:36:39 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))
14:36:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
14:36:39 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-y1zgei-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
14:36:39 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))
14:36:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
14:36:39 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-_h5yt9-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
14:36:39 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))
14:36:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
14:36:39 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-eetzmc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
14:36:39 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))
14:36:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
14:36:39 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-obnicm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
14:36:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///C:/slave/test/build/tests/mozbase/mozrunner in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 14))
14:36:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
14:36:39 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-cxyxny-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
14:36:39 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))
14:36:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
14:36:39 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-cxrcyc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
14:36:39 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))
14:36:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
14:36:39 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-wi93dm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
14:36:40 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))
14:36:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==0.17->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2))
14:36:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==0.17->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2))
14:36:40 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))
14:36:40 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))
14:36:40 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))
14:36:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10))
14:36:40 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
14:36:40 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
14:36:41 INFO - Downloading blessings-1.6.tar.gz
14:36:41 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings
14:36:41 INFO - Installing collected packages: blessings
14:36:41 INFO - Running setup.py install for blessings
14:36:41 INFO - Successfully installed blessings
14:36:41 INFO - Cleaning up...
14:36:44 INFO - Return code: 0
14:36:44 INFO - Done creating virtualenv C:\slave\test\build/venv.
14:36:44 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
14:36:44 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
14:36:44 INFO - Reading from file tmpfile_stdout
14:36:44 INFO - Using _rmtree_windows ...
14:36:44 INFO - Using _rmtree_windows ...
14:36:44 INFO - Current package versions:
14:36:44 INFO - blessings == 1.6
14:36:44 INFO - blobuploader == 1.2.4
14:36:44 INFO - docopt == 0.6.1
14:36:44 INFO - functools32 == 3.2.3-2
14:36:44 INFO - jsonschema == 2.5.1
14:36:44 INFO - manifestparser == 1.1
14:36:44 INFO - mozInstall == 1.12
14:36:44 INFO - mozcrash == 0.17
14:36:44 INFO - mozdebug == 0.1
14:36:44 INFO - mozdevice == 0.48
14:36:44 INFO - mozfile == 1.2
14:36:44 INFO - mozhttpd == 0.7
14:36:44 INFO - mozinfo == 0.9
14:36:44 INFO - mozleak == 0.1
14:36:44 INFO - mozlog == 3.4
14:36:44 INFO - moznetwork == 0.27
14:36:44 INFO - mozprocess == 0.23
14:36:44 INFO - mozprofile == 0.28
14:36:44 INFO - mozrunner == 6.12
14:36:44 INFO - mozscreenshot == 0.1
14:36:44 INFO - mozsystemmonitor == 0.3
14:36:44 INFO - moztest == 0.7
14:36:44 INFO - mozversion == 1.4
14:36:44 INFO - psutil == 3.1.1
14:36:44 INFO - requests == 1.2.3
14:36:44 INFO - Installing None into virtualenv C:\slave\test\build/venv
14:36:44 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')]}
14:36:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
14:36:44 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')]}
14:36:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
14:36:44 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 0x0186E640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01929E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0182D8F8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01944E90>, '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 0x012EA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F82520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-117', 'USERDOMAIN': 'T-W732-IX-117', '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~1.T-W\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W732-IX-117\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W732-IX-117', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-117', '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.T-W732-IX-117\\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.T-W732-IX-117', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
14:36:44 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
14:36:44 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
14:36:44 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
14:36:44 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W732-IX-117\\AppData\\Roaming',
14:36:44 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
14:36:44 INFO - 'COMPUTERNAME': 'T-W732-IX-117',
14:36:44 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
14:36:44 INFO - 'CYGWINBASE': 'C:\\cygwin',
14:36:44 INFO - 'DCLOCATION': 'SCL3',
14:36:44 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
14:36:44 INFO - 'FP_NO_HOST_CHECK': 'NO',
14:36:44 INFO - 'HOMEDRIVE': 'C:',
14:36:44 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W732-IX-117',
14:36:44 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
14:36:44 INFO - 'KTS_VERSION': '1.19c',
14:36:44 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W732-IX-117\\AppData\\Local',
14:36:44 INFO - 'LOGONSERVER': '\\\\T-W732-IX-117',
14:36:44 INFO - 'MONDIR': 'C:\\Monitor_config\\',
14:36:44 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
14:36:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
14:36:44 INFO - 'MOZ_NO_REMOTE': '1',
14:36:44 INFO - 'NO_EM_RESTART': '1',
14:36:44 INFO - 'NUMBER_OF_PROCESSORS': '8',
14:36:44 INFO - 'OS': 'Windows_NT',
14:36:44 INFO - 'OURDRIVE': 'C:',
14:36:44 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;',
14:36:44 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
14:36:44 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
14:36:44 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
14:36:44 INFO - 'PROCESSOR_LEVEL': '6',
14:36:44 INFO - 'PROCESSOR_REVISION': '1e05',
14:36:44 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
14:36:44 INFO - 'PROGRAMFILES': 'C:\\Program Files',
14:36:44 INFO - 'PROMPT': '$P$G',
14:36:44 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
14:36:44 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
14:36:44 INFO - 'PUBLIC': 'C:\\Users\\Public',
14:36:44 INFO - 'PWD': 'C:\\slave\\test',
14:36:44 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
14:36:44 INFO - 'SLAVEDIR': 'C:\\slave\\',
14:36:44 INFO - 'SYSTEMDRIVE': 'C:',
14:36:44 INFO - 'SYSTEMROOT': 'C:\\windows',
14:36:44 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp',
14:36:44 INFO - 'TEST1': 'testie',
14:36:44 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp',
14:36:44 INFO - 'USERDOMAIN': 'T-W732-IX-117',
14:36:44 INFO - 'USERNAME': 'cltbld',
14:36:44 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W732-IX-117',
14:36:44 INFO - 'WINDIR': 'C:\\windows',
14:36:44 INFO - 'WINDOWS_TRACING_FLAGS': '3',
14:36:44 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
14:36:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
14:36:45 INFO - Ignoring indexes: https://pypi.python.org/simple/
14:36:45 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))
14:36:45 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))
14:36:45 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))
14:36:45 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))
14:36:45 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))
14:36:45 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))
14:36:45 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))
14:36:45 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))
14:36:45 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))
14:36:45 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))
14:36:45 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))
14:36:45 INFO - Cleaning up...
14:36:45 INFO - Return code: 0
14:36:45 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv
14:36:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
14:36:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
14:36:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
14:36:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
14:36:45 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 0x0186E640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01929E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0182D8F8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01944E90>, '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 0x012EA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F82520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-117', 'USERDOMAIN': 'T-W732-IX-117', '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~1.T-W\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W732-IX-117\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W732-IX-117', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-117', '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.T-W732-IX-117\\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.T-W732-IX-117', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
14:36:45 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
14:36:45 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
14:36:45 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
14:36:45 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W732-IX-117\\AppData\\Roaming',
14:36:45 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
14:36:45 INFO - 'COMPUTERNAME': 'T-W732-IX-117',
14:36:45 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
14:36:45 INFO - 'CYGWINBASE': 'C:\\cygwin',
14:36:45 INFO - 'DCLOCATION': 'SCL3',
14:36:45 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
14:36:45 INFO - 'FP_NO_HOST_CHECK': 'NO',
14:36:45 INFO - 'HOMEDRIVE': 'C:',
14:36:45 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W732-IX-117',
14:36:45 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
14:36:45 INFO - 'KTS_VERSION': '1.19c',
14:36:45 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W732-IX-117\\AppData\\Local',
14:36:45 INFO - 'LOGONSERVER': '\\\\T-W732-IX-117',
14:36:45 INFO - 'MONDIR': 'C:\\Monitor_config\\',
14:36:45 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
14:36:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
14:36:45 INFO - 'MOZ_NO_REMOTE': '1',
14:36:45 INFO - 'NO_EM_RESTART': '1',
14:36:45 INFO - 'NUMBER_OF_PROCESSORS': '8',
14:36:45 INFO - 'OS': 'Windows_NT',
14:36:45 INFO - 'OURDRIVE': 'C:',
14:36:45 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;',
14:36:45 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
14:36:45 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
14:36:45 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
14:36:45 INFO - 'PROCESSOR_LEVEL': '6',
14:36:45 INFO - 'PROCESSOR_REVISION': '1e05',
14:36:45 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
14:36:45 INFO - 'PROGRAMFILES': 'C:\\Program Files',
14:36:45 INFO - 'PROMPT': '$P$G',
14:36:45 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
14:36:45 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
14:36:45 INFO - 'PUBLIC': 'C:\\Users\\Public',
14:36:45 INFO - 'PWD': 'C:\\slave\\test',
14:36:45 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
14:36:45 INFO - 'SLAVEDIR': 'C:\\slave\\',
14:36:45 INFO - 'SYSTEMDRIVE': 'C:',
14:36:45 INFO - 'SYSTEMROOT': 'C:\\windows',
14:36:45 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp',
14:36:45 INFO - 'TEST1': 'testie',
14:36:45 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp',
14:36:45 INFO - 'USERDOMAIN': 'T-W732-IX-117',
14:36:45 INFO - 'USERNAME': 'cltbld',
14:36:45 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W732-IX-117',
14:36:45 INFO - 'WINDIR': 'C:\\windows',
14:36:45 INFO - 'WINDOWS_TRACING_FLAGS': '3',
14:36:45 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
14:36:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
14:36:45 INFO - Ignoring indexes: https://pypi.python.org/simple/
14:36:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages
14:36:45 INFO - Cleaning up...
14:36:45 INFO - Return code: 0
14:36:45 INFO - Running post-action listener: _resource_record_post_action
14:36:45 INFO - Running post-action listener: _start_resource_monitoring
14:36:45 INFO - Starting resource monitoring.
14:36:45 INFO - [mozharness: 2016-09-28 21:36:45.754000Z] Finished create-virtualenv step (success)
14:36:45 INFO - [mozharness: 2016-09-28 21:36:45.754000Z] Running install step.
14:36:45 INFO - Running pre-action listener: _resource_record_pre_action
14:36:45 INFO - Running main action method: install
14:36:45 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
14:36:45 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
14:36:46 INFO - Reading from file tmpfile_stdout
14:36:46 INFO - Using _rmtree_windows ...
14:36:46 INFO - Using _rmtree_windows ...
14:36:46 INFO - Detecting whether we're running mozinstall >=1.0...
14:36:46 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h']
14:36:46 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h
14:36:46 INFO - Reading from file tmpfile_stdout
14:36:46 INFO - Output received:
14:36:46 INFO - Usage: mozinstall-script.py [options] installer
14:36:46 INFO - Options:
14:36:46 INFO - -h, --help show this help message and exit
14:36:46 INFO - -d DEST, --destination=DEST
14:36:46 INFO - Directory to install application into. [default:
14:36:46 INFO - "C:\slave\test"]
14:36:46 INFO - --app=APP Application being installed. [default: firefox]
14:36:46 INFO - Using _rmtree_windows ...
14:36:46 INFO - Using _rmtree_windows ...
14:36:46 INFO - mkdir: C:\slave\test\build\application
14:36:46 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.zip', '--destination', 'C:\\slave\\test\\build\\application']
14:36:46 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py C:\slave\test\build\firefox-52.0a1.en-US.win32.zip --destination C:\slave\test\build\application
14:36:49 INFO - Reading from file tmpfile_stdout
14:36:49 INFO - Output received:
14:36:49 INFO - C:\slave\test\build\application\firefox\firefox.exe
14:36:49 INFO - Using _rmtree_windows ...
14:36:49 INFO - Using _rmtree_windows ...
14:36:49 INFO - Running post-action listener: _resource_record_post_action
14:36:49 INFO - [mozharness: 2016-09-28 21:36:49.020000Z] Finished install step (success)
14:36:49 INFO - [mozharness: 2016-09-28 21:36:49.020000Z] Running run-tests step.
14:36:49 INFO - Running pre-action listener: _resource_record_pre_action
14:36:49 INFO - Running main action method: run_tests
14:36:49 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version']
14:36:49 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version
14:36:49 INFO - Python 2.7.3
14:36:49 INFO - Return code: 0
14:36:49 INFO - grabbing minidump binary from tooltool
14:36:49 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')]}
14:36:49 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 0x01944E90>, '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 0x012EA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F82520>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1
14:36:49 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
14:36:49 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
14:36:49 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
14:36:49 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'...
14:36:49 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmpnmwblw
14:36:49 INFO - INFO - File integrity verified, renaming tmpnmwblw to win32-minidump_stackwalk.exe
14:36:49 INFO - Return code: 0
14:36:49 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755
14:36:49 INFO - mkdir: C:\slave\test\build\blobber_upload_dir
14:36:49 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir
14:36:49 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe
14:36:49 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir
14:36:49 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ff45gAeUSMmiMPeyn1GlEA/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-117', '--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
14:36:49 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --branchName Autoland-Non-PGO --suite dromaeojs --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/ff45gAeUSMmiMPeyn1GlEA/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-117 --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
14:36:49 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
14:36:49 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W732-IX-117\\AppData\\Roaming',
14:36:49 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
14:36:49 INFO - 'COMPUTERNAME': 'T-W732-IX-117',
14:36:49 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
14:36:49 INFO - 'CYGWINBASE': 'C:\\cygwin',
14:36:49 INFO - 'DCLOCATION': 'SCL3',
14:36:49 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
14:36:49 INFO - 'FP_NO_HOST_CHECK': 'NO',
14:36:49 INFO - 'HOMEDRIVE': 'C:',
14:36:49 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W732-IX-117',
14:36:49 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
14:36:49 INFO - 'KTS_VERSION': '1.19c',
14:36:49 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W732-IX-117\\AppData\\Local',
14:36:49 INFO - 'LOGONSERVER': '\\\\T-W732-IX-117',
14:36:49 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir',
14:36:49 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe',
14:36:49 INFO - 'MONDIR': 'C:\\Monitor_config\\',
14:36:49 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
14:36:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
14:36:49 INFO - 'MOZ_NO_REMOTE': '1',
14:36:49 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir',
14:36:49 INFO - 'NO_EM_RESTART': '1',
14:36:49 INFO - 'NUMBER_OF_PROCESSORS': '8',
14:36:49 INFO - 'OS': 'Windows_NT',
14:36:49 INFO - 'OURDRIVE': 'C:',
14:36:49 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;',
14:36:49 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
14:36:49 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
14:36:49 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
14:36:49 INFO - 'PROCESSOR_LEVEL': '6',
14:36:49 INFO - 'PROCESSOR_REVISION': '1e05',
14:36:49 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
14:36:49 INFO - 'PROGRAMFILES': 'C:\\Program Files',
14:36:49 INFO - 'PROMPT': '$P$G',
14:36:49 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
14:36:49 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
14:36:49 INFO - 'PUBLIC': 'C:\\Users\\Public',
14:36:49 INFO - 'PWD': 'C:\\slave\\test',
14:36:49 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos',
14:36:49 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
14:36:49 INFO - 'SLAVEDIR': 'C:\\slave\\',
14:36:49 INFO - 'SYSTEMDRIVE': 'C:',
14:36:49 INFO - 'SYSTEMROOT': 'C:\\windows',
14:36:49 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp',
14:36:49 INFO - 'TEST1': 'testie',
14:36:49 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp',
14:36:49 INFO - 'USERDOMAIN': 'T-W732-IX-117',
14:36:49 INFO - 'USERNAME': 'cltbld',
14:36:49 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W732-IX-117',
14:36:49 INFO - 'WINDIR': 'C:\\windows',
14:36:49 INFO - 'WINDOWS_TRACING_FLAGS': '3',
14:36:49 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
14:36:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
14:36:49 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ff45gAeUSMmiMPeyn1GlEA/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-117', '--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
14:36:50 INFO - mozversion application_buildid: 20160928130720
14:36:50 INFO - mozversion application_changeset: f36d1ab003c857b9861e3ca111c7e152e2a86af3
14:36:50 INFO - mozversion application_display_name: Nightly
14:36:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
14:36:50 INFO - mozversion application_name: Firefox
14:36:50 INFO - mozversion application_remotingname: firefox
14:36:50 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland
14:36:50 INFO - mozversion application_vendor: Mozilla
14:36:50 INFO - mozversion application_version: 52.0a1
14:36:50 INFO - mozversion platform_buildid: 20160928130720
14:36:50 INFO - mozversion platform_changeset: f36d1ab003c857b9861e3ca111c7e152e2a86af3
14:36:50 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland
14:36:50 INFO - mozversion platform_version: 52.0a1
14:36:50 INFO - using testdate: 1475098610
14:36:50 INFO - actual date: 1475098610
14:36:50 INFO - starting webserver on 'localhost:49321'
14:36:50 INFO - SUITE-START | Running 2 tests
14:36:50 INFO - TEST-START | dromaeo_css
14:36:50 INFO - operating with platform_type : w7_
14:36:50 INFO - Initialising browser for dromaeo_css test...
14:36:50 INFO - TEST-INFO | started process 2228 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld~1.t-w\appdata\local\temp\tmpwtzfnn\profile http://localhost:49321/getInfo.html)
14:36:57 INFO - PROCESS | 2228 | __metrics Screen width/height:1600/1200
14:36:57 INFO - PROCESS | 2228 | colorDepth:24
14:36:57 INFO - PROCESS | 2228 | Browser inner width/height: 1010/674
14:36:57 INFO - PROCESS | 2228 | __metrics
14:36:59 INFO - PROCESS | 2228 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows"
14:36:59 INFO - PROCESS | 2228 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
14:37:00 INFO - TEST-INFO | 2228: exit 0
14:37:00 INFO - Browser initialized.
14:37:00 INFO - Running cycle 1/1 for dromaeo_css test...
14:37:00 INFO - TEST-INFO | started process 1124 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld~1.t-w\appdata\local\temp\tmpwtzfnn\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1)
14:37:01 INFO - PROCESS | 1124 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0
14:37:12 INFO - PROCESS | 1124 | RSS: Main: 139517952
14:37:12 INFO - PROCESS | 1124 |
14:38:03 INFO - PROCESS | 1124 | 1475098683350 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no]
14:40:15 INFO - PROCESS | 1124 | Cycle 1(1): loaded http://localhost:49321/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49321/tests/dromaeo/cssquery-ext.html)
14:40:16 INFO - PROCESS | 1124 | RSS: Main: 184680448
14:40:16 INFO - PROCESS | 1124 |
14:43:19 INFO - PROCESS | 1124 | Cycle 1(1): loaded http://localhost:49321/tests/dromaeo/cssquery-ext.html (next: http://localhost:49321/tests/dromaeo/cssquery-jquery.html)
14:43:20 INFO - PROCESS | 1124 | RSS: Main: 202543104
14:43:20 INFO - PROCESS | 1124 |
14:46:22 INFO - PROCESS | 1124 | Cycle 1(1): loaded http://localhost:49321/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49321/tests/dromaeo/cssquery-mootools.html)
14:46:23 INFO - PROCESS | 1124 | RSS: Main: 144678912
14:46:23 INFO - PROCESS | 1124 |
14:49:25 INFO - PROCESS | 1124 | Cycle 1(1): loaded http://localhost:49321/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49321/tests/dromaeo/cssquery-prototype.html)
14:49:26 INFO - PROCESS | 1124 | RSS: Main: 138014720
14:49:26 INFO - PROCESS | 1124 |
14:52:29 INFO - PROCESS | 1124 | Cycle 1(1): loaded http://localhost:49321/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49321/tests/dromaeo/cssquery-yui.html)
14:52:29 INFO - PROCESS | 1124 | RSS: Main: 138371072
14:52:29 INFO - PROCESS | 1124 |
14:55:32 INFO - PROCESS | 1124 | Cycle 1(1): loaded http://localhost:49321/tests/dromaeo/cssquery-yui.html (next: http://localhost:49321/tests/dromaeo/cssquery-dojo.html)
14:55:32 INFO - PROCESS | 1124 | RSS: Main: 189788160
14:55:32 INFO - PROCESS | 1124 |
14:55:32 INFO - PROCESS | 1124 | __start_tp_report
14:55:32 INFO - PROCESS | 1124 | _x_x_mozilla_page_load
14:55:32 INFO - PROCESS | 1124 | _x_x_mozilla_page_load_details
14:55:32 INFO - PROCESS | 1124 | |i|pagename|runs|
14:55:32 INFO - PROCESS | 1124 | |0;dojo.html;2159.0176469706284;2349;2350.2596682045155;2350.989074697879;2359.7050368703913;3950.071733142709;3989.1423343981037;4015.99600099975;4092.85675001375;4095.201435720032;3758.887233382996;3896.4544963705102;3951.091249012729;3995.0411901143734;4053.1691003344317;3435.673611006963;3503.842327095274;3509.7016753575977;3517.50754894315;3530.3645343838098;3467.601225859033;3508.7719298245534;3537.00963730156;3540.3273378058216;3583.7491375603718;3543.6279190684863;3568.4290513517844;3574.7855128692363;3575.0704816747575;3581.212133330663;3319.286353433999;3341.7159541439005;3395.5585773849243;3491.423915054013;3550.7159427246015;3494.860205591773;3513.8770143045126;3547.7516573839844;3555.9111022224392;3567.8216089195435;3931.6658084062888;3979.184267225241;4007.358822588401;4011.498562679665;4019.839206431739;4097.078157414588;4118.91762164755;4135.359019351975;4141.399497072907;4255.063885945087;4138.0689344897455;4240.363945408182;4252.766097864616;4262.957370426318;4301.655867530621;3857.325318197309;3938.212357528506;4006.098627808778;4024;4024.7383920045104;4158.6673066154635;4239.788010599457;4263.5736426357735;4273.55127711592;4365.628921541641;4076.5515793262716;4132.8140233689555;4144.730592511537;4205.915881682349;4206.348016057516;4002;4183.184279065553;4258.787060647017;4266.765327906995;4267.935980960288;4014.698897582693;4131.318332475168;4215.515215750167;4229.555896630809;4246.129543443587;4060.1879624074713;4180.393842892763;4216.683748718859;4224.387463817729;4259.723117997382;3017.456857765623;3045.8324792136646;3048.131282584453;3082.96720598602;3090.1193159949307;2749.340158362019;2760.3375189954268;2799.3981294021883;2804.1587523743206;2812.2547524905917;3777.962220377816;3843.3466310726685;3874.845006199777;3930.0764320384687;4022.7385219961193;4133.090720041586;4322.481302243751;4332.0469496710675;4332.653387728974;4334.9783251083545;3975.8012099394914;3982.7012974027066;3988.66096381805;4032.03231224504;4061.756294622332;3513.1744040150543;3560.4481305397703;3600.135967367813;3608.7654302470255;3690.7970061646865;4222.261104306734;4331.45856767904;4401.911961760747;4428.380026796315;4439.178751930903;4209.200251952119;4275.380069889787;4283.614474697292;4288.742675439484;4297.82808687649;4259.701820872509;4306.375575541591;4325.589069038437;4421.778911054499;4423.447069116361;4139.234241665302;4183.811728472287;4193.517745459311;4235.216484950294;4248.681348898783;4147.440095587178;4233.449651545279;4298.699091063596;4312.956870431255;4313.590208930146;4090.0592863640936;4091.754494730326;4154.231467178582;4155.750654960712;4160.687948403821;4019.9598004019585;4029.879103626896;4044.1305119398294;4083.326251168524;4102.91794164121;3980.840766369429;4005.0588111794286;4085.77528235938;4089.386592011222;4089.631933126033;4114.814833332447;4130.648894843972;4134.157262234085;4209.894752631209;4210.263203939359;3998.7400818946676;4036.2936486114227;4040.41413994975;4065.8576949806616;4066.6543343816106
14:55:32 INFO - PROCESS | 1124 | |1;ext.html;505.335483838752;511.13873123786425;518.2666526864487;535.7990753467452;549.5493695169962;25482.362940926487;25590.360240993985;35823.4626480603;36489.452658210146;36601.45097823535;36784.81607591965;37156.44265336022;37884;37911.24177516448;38055.23889522208;6407.487401007931;7518.135414427329;7680.1551829299015;7688.5771282579435;7689.077310722721;6418.293987661354;6544.738210471575;6579.835504112411;6580;9209.171174594285;9178.357514973955;9866.950665246664;9921.553530091163;9938.652147174851;9941.950290248538;9692.46691431971;9850.359726617819;9854.556544955494;9864.802703945952;9865;6295.559310848243;6460.450861676763;9490.620375184984;9575.09036641518;9669.903300966971;33732;37183.07042323936;37214;37220.627793721986;37260.62739372599;2430.5989511730545;2437.8659173745436;2484.776370126697;2505.7870081043134;2534.7085085215253;26210.34474138143;26285.474290514274;26305.473890522273;26573;27043.729562704513;3137.6705445928305;3156.936861262762;3357.3788849063003;3363.394588973984;3379.645137260577;208876;209059;209072;211408.94295528578;211645;43453;45144.774276129065;45158.09683806305;45164.77417612957;45172.77413612911;47290;47465.28802067972;47500;47553;47576.524234757206;10449.634262800917;10486.108680762221;10653.094486968539;10658.413787241776;10680.62617808373;7890.132085470594;7944.682212711542;7950.244726751065;7953.721619743281;7963.522188668699;12113.39433028345;12596.370181490889;12659.936700316439;13490.865091349157;13671.15439415686;5497.51567076887;5546.278983732089;5571.582131340166;5581.413951535112;5584.720763961785;746.1493896957472;748.2143749063462;776.281939206232;778.6106946526737;835.9623816928253;22208.55582888333;24334.878325608257;24570.140045098335;24574.754252457245;24668.5066298673;868.379108937113;871.9476831390136;880.5949263338936;890.2655309369667;969.8496733006396;13026;13041.482013814159;13123.409446574928;13207.471701132039;13365.599032029055;279488;281485;286122;289291;290051;36688.44967325492;43543;43545.78227108844;43558.34662480065;43632;953.618552578974;1048.6487026846091;1072.099436473366;1085.0451602589671;1085.7122862441458;24380.75619243785;25026.297553704168;25941;25992.61011084873;26049;37098.8145059273;37158.44262336121;37526;37542.812285938395;37597;247701;248416;248929.77675558257;275326;314967;20843.895780521;21044.579108418053;21050.2632407865;21062.894685527088;21095.683564746236;37505.2498950025;37646;37685.81157094197;37901.05247368838;37944.43083353807;31128.53307200439;31647;32031.199220019687;32284.35431291408;32719.345613087127;31911.84044079765;32179.58001448207;32270.677293227705;32506.512402314456;32535.349293013533;29713.25716857096;30461.39077218399;30791.69208307978;32394.676053239167;33358.33283334271;28532.42935141244;29173.124806255848;29235.7076429233;29434.411311774074;29610.407791844475;22828.771712283327;23071.53856922886;23181.652275215547;23299.76700232976;23384
14:55:32 INFO - PROCESS | 1124 | |2;jquery.html;289.29556529849805;320.4504275168087;323.08244585377525;324.9528818321343;329.58472324870655;11406;15384.384624615002;15552.611184720374;15814.209289535534;16057.83942160578;14905.552833415015;15134;15670.608234794136;15674.843251567481;15682.059076455393;4887.902241955158;5068.518490743392;5073.7209453480045;5083.18669012958;5090.981803639268;4826.1795494765975;4992.700437973734;4994.200927851544;5070.366204224472;5076.492350764913;4982.302477653131;4994.700317980914;5000.19996800512;5003.349564556603;5007.449180590151;4977.104121258172;4980.20316749322;4980.651354405193;4985.975070124644;4989;4243.511996120424;4401.581849724271;4413.801378937956;4433.889152771174;4439.5560443955665;19484.70772938407;19513.80486195134;19706.605867882707;19756.407307780788;19793.74434496576;1677.421289655067;1701.7617533545313;1741.033726281913;1765.417412253966;1806.5573934386027;12291.262524248574;12992.155509891922;13153.408096635674;13195.20828750278;13343.199408035453;1429.1210905293274;1530.1278271385315;1533.401973230441;1571.7485202367566;1574.3387777133632;45035.77482112601;59075.40924590742;61617.691911540605;64032.67983660098;64438;8493.660253589787;8754.606042728092;8792;8911.331650126267;8950.552472376356;11389.715257118638;11585.478653460614;11627.883721162849;11644.941775291069;11645.708857278467;6113.266408030976;6128.417800308964;6128.571000030044;6229.688515574203;6255.967765318672;5632.239647647515;5708.543316534668;5824.223611798442;5827.009408400582;5836.299644042657;5493.505584497414;5579.721013949367;5593.580481463907;5598.916016259759;5670.801521946794;1580.968380632381;1595.6489572294076;1601.9599510012342;1610.4846449136398;1622.5132460261875;623.8721062182242;814.2346194577196;826.8925039744795;857.4555157474923;891.1044400377579;16066.196690165445;16259.593510162103;16683.6663266734;16809.411670591715;17041.14794260282;750.8723517002122;754.1252147508864;784.5136015670321;807.2976510435841;886.9711135083272;7311.305425984523;8302.086770455246;8702;9094.408863423856;9159.267258619295;270741;277978;277980;278303;279003;44865.55134448679;71104;71815.2818471819;74471;74808.62595686987;828.5815663090101;859.0035558751943;859.8366310401004;860.7589874835056;863.4819108534955;14621.488247911271;15025.774613380589;15144.621384465476;15236.542903712907;15298.464553740565;36202.8189859049;36655.81672091623;36928.630713693594;37808.24383512369;38029.42955855609;49807.501924980286;50152.498475014785;50335.74832125816;51072.489275106775;52133.7393313031;6089.878202436015;6105.9694701526205;6230.626162430269;6252.562320637512;6338.58799178052;7238.420926325986;8724.56377181151;9209.723708288762;9233.445993240159;9535.23718102564;7062.329078737512;7144.3927266182955;7150.284971502808;7160.427165826617;7175.928240717526;7638.961805191161;7741.380689544935;7743.92256077432;7840.72557460509;7868.881966770388;7229.421646268391;7314.305141011595;7343.559386436617;7552.358049565849;7799.727009554638;6207.34822843613;6214.8135555933395;6228.785386849521;6236.407541283571;6437.195350581177;7096;7154.856902862018;7209.891851622335;7218.602976836324;7376.262373762581
14:55:32 INFO - PROCESS | 1124 | |3;mootools.html;1175.5944199251255;1190.422645017167;1264.6964728465168;1495.6260934766308;1503.270913606901;5493.121100623891;5704.942950570493;6113.694315284235;6750.054992301082;6759.493038022149;5609.046462101453;5746.885062298762;6415.101885736;6557.901631475532;6570.934290657092;1837.072278499356;1850.1581780290003;1855.6567035098483;1857.0065015216862;1859.051883539398;1712.1952682239328;1735.4359833054245;1762.6827171109196;1767.5934535056945;1773.1045821860007;1568.7411577089824;1618.8057433107986;1621.2137113499944;1632.2899538700644;1633.901965882051;1769.1773325403685;1839.613681126965;1840.6962851129547;1841.604055128154;1842.1802297977406;1812.9093545322812;1822.1071674879347;1847.4272975377676;1853.3142737187327;1853.5180852978187;6240.906386404207;6392.872142557169;6504.121943537609;6509.8047058587845;6514.055461958037;3704.203596226824;3805.0677583991824;3822.0444888777806;3828.2152158807376;3834.2331533693095;2506.1604362538574;2552.0812507497285;2555.514452254066;2561.1036137351966;2563.090103013422;830.5930094253772;831.575896292895;870.08640927027;871.0810095349403;876.9342299327576;8179.550124743137;8314.584270786436;8333.624986875606;8362.958185209096;8364.247217750431;3306.3552607241836;3337.3825842219267;3355.5134505496567;3373.730101591867;3414.8804791832167;7074.540154889915;7333.669984850694;7336.082989626297;7345.632718364167;7354.889676654854;3684.502592150079;3701.2967536168044;3742;3817.1602247505502;3893;3563.2160924597065;3578.8389522471552;3586.55168103987;3587.677109060197;3591.3715099857423;2928.428956353491;2963.5406511990677;2988.6861879502776;3059.602251707264;3066.294752207005;976.951152442375;999.1307562420595;1001.0690058245756;1007.6120693533043;1009.0867764672983;682.6654939079815;696.9512134150663;704.1761139466836;708.125465050662;717.0713925466533;3725.6096951773757;4243.8090285937205;4396.384506169139;4421.624161946271;4625.1443482955765;735.4410647907629;764.900562926816;793.595266414133;797.2266901105918;808.6361137488154;2699.460107978412;2765.861706914646;3191.5478457301874;3225.9838700806813;3233.8221397822876;7200.747973820891;7518.624068796538;7598.506097103671;7650.502717323357;7693.538387696756;7485.85028299431;7696.230376962371;7709.884351734729;7715.035620547432;7736.342410895137;5951.464368206882;5977.252843394575;6119.051547010221;6149.66176860268;6149.723262453201;4403.9119217614825;4492.977535112434;4515.14212299662;4533.977330113328;4560.201964656106;7136.892946605909;7300;7380.261973802577;7439.256074392518;7471.813204669709;7149.249328820399;7225.5664660120565;7484.25157484269;7501.324880760758;7501.774946751606;5404.54061404785;5473.562115030708;5489.917651235155;5490.917636235539;5503.036968530571;5933.258342707161;5950.077737950624;5964.463198312173;6166.229221347332;6218.720157592828;5247.16045432729;5309.2301616266195;5363.2759577456545;5475.917861232005;5575.303087114111;4736.834210802605;5103.897922041464;5294.602904782234;5308.071087559584;5364.3830959440165;5118.3090282811345;5174.14626586609;5186.2479940409185;5191.454897235887;5195.532402083831;3407.557017587698;3527.2416430467574;3577.982110089433;3585.6772890439984;3599.6760291573883;3521.5950165730246;3589.730770192298;3640.7633503822167;3697.519322488167;3711.740178187501
14:55:32 INFO - PROCESS | 1124 | |4;prototype.html;226.81287937451586;233.81411777636777;235.06327285766224;236.92181580078577;237.65658623289332;4328.6753493487995;4356.869293921183;4365.345198220269;4369.410129632499;4371.934420983688;4116.444280022196;4167.854125105622;4223.957760422395;4225.492940847103;4290.420793192918;3114.5639610454555;3146.2763564380207;3175.968240317602;3190.585223920887;3205.214722393017;3202.67973202679;3205.7657801746263;3209.277912469687;3209.4383482890516;3220.2593403517203;2952.232419570916;3097.5973123493914;3107.2231942014496;3187.42626327261;3193.952090718641;3165.7625678074005;3180.393901079963;3183.188286986827;3183.649798522161;3194.488881778916;2768.183385901164;2774.556071028646;2774.987129697666;2776.6806817216075;2780.5968134620366;4022.235775202702;4179.414881916534;4190.874273771792;4193.1404062167185;4222.493300803892;3713.702903767692;3730.0674831292176;3739.3082279778328;3740.251949610089;3741.4761933329355;4309.461317335333;4345.022369966764;4421.248387774086;4429.42417485728;4431.955680443187;3113.097201811472;3119.2357872321427;3124.109628755794;3128.4994400895976;3130.951131370971;4594.448666160049;4637.026224492861;4638.397008388922;4639.18814207509;4651.720896746206;3176.332970076264;3181.3955348483714;3181.904542863718;3186.2193762527863;3197.344544368399;3138.2468207630004;3149.7637677173843;3220.3076338587316;3224.1133688235923;3224.3394651754365;3597.208614104945;3675.7610755301353;3703.666669999713;3721.330160571069;3725.8882233533036;4429.623482004065;4459.420275364182;4460.843870464518;4471.837322296226;4480.215962206601;1096.961606343774;1118.8769235384102;1120.7086157599083;1125.2910666280354;1126.380490730095;693.9479539034492;717.1000394504968;722.6061796321018;747.8055705516605;772.3589420780851;4215.515215750167;4246.40550322955;4261.275583150872;4263.445752052214;4275;4479.395281636937;4533.818647254073;4545.5908968193025;4578.725276483422;4584.839530616479;4216.945763559111;4232.830686772556;4239.448871646666;4241.491021077491;4250.914981700349;2328.7438381777993;2375.9524809503714;2395.568797616446;2417.62526808345;2426.551088048717;4763.69036012658;4769.904601907942;4771.761411929459;4784.880377990578;4796.8081276748535;4677.836275730333;4723.362346083235;4733.5503127202865;4736.95263047372;4740.265258884879;4783.115123702126;4786.8085276589245;4796.9760151199025;4797.424309082862;4809.807607695723;4395.714278572011;4623.144718227139;4634.37435946143;4639.30410438437;4640.489546149988;4519.84180553679;4547.681662283609;4547.749873756975;4556.977215113903;4557.293619488984;4770.403699537558;4772.451168115711;4786.425628924551;4799.424069111729;4808.341122312797;3016.4721173794937;3044.2541577312813;3084.691530846987;3095.90712278632;3103.162146220553;4518.344839998247;4569.862904112882;4582.839600613963;4582.88542786433;4598.793054312497;4162.79279009084;4373.540778218241;4401.933970990502;4416.470023597189;4425.026494171278;4350.891227719332;4393.516713161485;4416.271315233079;4437.889052773707;4441.911161776811;4221.176870510344;4323.308270676676;4325.653947684241;4343.5656434356315;4357.716748411343;4447.3329000650165;4471.949091963321;4478.373027776179;4484.327350897261;4487.730736155841;4254.383114448449;4405.31717583775;4433.6009759121835;4455.732656040648;4456.777161141995
14:55:32 INFO - PROCESS | 1124 | |5;yui.html;564.9378568357481;589.4017572164254;597.3936454498681;607.271274470635;649.2014821769224;11810.40947952602;12559.811602825965;12649.620511384674;12979.091463597551;12980.805287920688;12379.133460657758;12475.937620311886;12481.31352775597;12489.562865299717;12513.124081314312;507.3885967409263;518.3883018038713;641.7497176101324;644.3202421445374;656.26826088911;629.9622022678631;645.7675236914707;651.1079820645722;652.1098700274139;652.7845810882421;670.4200866250683;684.8356394465318;685.099094690483;685.6366125953227;686.3753983874675;530.9150535914235;532.8001999250281;541.7237209023388;681.0601370109242;683.0812557110678;402.63561476863487;403.51175078155467;494.94308154561963;496.12434053894907;500.279597379772;11222.943885280603;11295;11443;11551.942240288829;11566.363849988247;11528.250663706833;11571.772613271996;11724.072435734039;11788.528458861636;11843.28940263587;11080.335179889233;11081.501332440059;11154.776904461865;11154.996050355427;11248.156388270912;4031.3348297530874;4082.0815316553835;4126.66986641071;4210.32634778437;4243.851465198734;62834.68975584393;63259.36740632581;64272.3572764271;64559.3544064558;65748.6712566439;8824.470531768115;8875.28997680184;9040.909590904006;9184.586693598803;9273.350865439354;16224.513264602081;16302.673946521003;16377.344906203856;16495;16593.170341482877;6114.877702446015;6128.448439640454;6211.81364559064;6234.844128896723;6270.435660790551;3348.162959260185;3377.5102610121394;3397.371486275047;3405.7956522608724;3430.519727238189;2343.9296821095395;2387.606045002555;2428.757124287557;2455.693038370204;2459.5080983802955;422.391755871544;423.85165192183103;431.67839959230577;436.58524401817766;437.5252850657073;472.3033525549855;492.71422574906467;506.72383550964815;521.439452588469;545.3510322715955;7209.96395018032;7322.267773222742;7340.192578816326;7524.548527088392;7661.770146895602;468.75311670955097;482.8961773218705;558.6731761919242;562.7102042448142;583.9854003649942;3636.581793093828;3639.70882329413;3660;3686.705063594906;3772.320982223226;76115;81626;91134;94533;95515;64137.67931160408;67516;70077.64961175162;72948.54102918018;73527.63236183891;44168.779156104654;44748.77625611851;67395.99999999903;70071;70174.29825701882;7330.5968171751065;7544.962275188589;7799.9220007799195;7803.258690424401;7966.880496792437;39778.403323949584;40293.39559906662;42267.7886610565;42422;43628.781856090514;56490;57167;57230.42769572251;57238;60023.39976600353;10444.68665940023;10654.57381704723;10661.307015043998;10753.677389678322;10765.246432749946;10794.622188223375;11085.50115244832;11134.33194008362;11140.554377824796;11253.71865703364;10621.681349559525;10833.566657333618;10856.968587984129;10858.185636077484;10943.45282735876;7357.337839594462;10317.329373590692;10554.419506927192;10626.52180651857;10897.891021089688;10543.525541350504;10691.679249622524;10706.357618542912;10743.462826858782;10878.891211087788;8919.777005574653;9602.807943841224;9620.663276785559;9740.805183896424;9876.506174691092;8821.544142463068;8926.821463570823;8990.955045224993;9377.296702747457;9405.153536181777
14:55:32 INFO - PROCESS | 1124 | __end_tp_report
14:55:32 INFO - PROCESS | 1124 | __start_cc_report
14:55:32 INFO - PROCESS | 1124 | _x_x_mozilla_cycle_collect,912
14:55:32 INFO - PROCESS | 1124 | __end_cc_report
14:55:32 INFO - PROCESS | 1124 | __startTimestamp1475099732778__endTimestamp
14:55:32 INFO - PROCESS | 1124 |
14:55:32 INFO - PROCESS | 1124 | ------- Summary: start -------
14:55:32 INFO - PROCESS | 1124 | Number of tests: 6
14:55:32 INFO - PROCESS | 1124 |
14:55:32 INFO - PROCESS | 1124 | [#0] dojo.html Cycles:165 Average:3898.38 Median:4060.19 stddev:469.16 (11.6%) stddev-sans-first:450.32
14:55:32 INFO - PROCESS | 1124 | Values: 2159.0 2349.0 2350.3 2351.0 2359.7 3950.1 3989.1 4016.0 4092.9 4095.2 3758.9 3896.5 3951.1 3995.0 4053.2 3435.7 3503.8 3509.7 3517.5 3530.4 3467.6 3508.8 3537.0 3540.3 3583.7 3543.6 3568.4 3574.8 3575.1 3581.2 3319.3 3341.7 3395.6 3491.4 3550.7 3494.9 3513.9 3547.8 3555.9 3567.8 3931.7 3979.2 4007.4 4011.5 4019.8 4097.1 4118.9 4135.4 4141.4 4255.1 4138.1 4240.4 4252.8 4263.0 4301.7 3857.3 3938.2 4006.1 4024.0 4024.7 4158.7 4239.8 4263.6 4273.6 4365.6 4076.6 4132.8 4144.7 4205.9 4206.3 4002.0 4183.2 4258.8 4266.8 4267.9 4014.7 4131.3 4215.5 4229.6 4246.1 4060.2 4180.4 4216.7 4224.4 4259.7 3017.5 3045.8 3048.1 3083.0 3090.1 2749.3 2760.3 2799.4 2804.2 2812.3 3778.0 3843.3 3874.8 3930.1 4022.7 4133.1 4322.5 4332.0 4332.7 4335.0 3975.8 3982.7 3988.7 4032.0 4061.8 3513.2 3560.4 3600.1 3608.8 3690.8 4222.3 4331.5 4401.9 4428.4 4439.2 4209.2 4275.4 4283.6 4288.7 4297.8 4259.7 4306.4 4325.6 4421.8 4423.4 4139.2 4183.8 4193.5 4235.2 4248.7 4147.4 4233.4 4298.7 4313.0 4313.6 4090.1 4091.8 4154.2 4155.8 4160.7 4020.0 4029.9 4044.1 4083.3 4102.9 3980.8 4005.1 4085.8 4089.4 4089.6 4114.8 4130.6 4134.2 4209.9 4210.3 3998.7 4036.3 4040.4 4065.9 4066.7
14:55:32 INFO - PROCESS | 1124 |
14:55:32 INFO - PROCESS | 1124 | [#1] ext.html Cycles:180 Average:39570.75 Median:18555.82 stddev:67179.26 (362.0%) stddev-sans-first:67303.68
14:55:32 INFO - PROCESS | 1124 | Values: 505.3 511.1 518.3 535.8 549.5 25482.4 25590.4 35823.5 36489.5 36601.5 36784.8 37156.4 37884.0 37911.2 38055.2 6407.5 7518.1 7680.2 7688.6 7689.1 6418.3 6544.7 6579.8 6580.0 9209.2 9178.4 9867.0 9921.6 9938.7 9942.0 9692.5 9850.4 9854.6 9864.8 9865.0 6295.6 6460.5 9490.6 9575.1 9669.9 33732.0 37183.1 37214.0 37220.6 37260.6 2430.6 2437.9 2484.8 2505.8 2534.7 26210.3 26285.5 26305.5 26573.0 27043.7 3137.7 3156.9 3357.4 3363.4 3379.6 208876.0 209059.0 209072.0 211408.9 211645.0 43453.0 45144.8 45158.1 45164.8 45172.8 47290.0 47465.3 47500.0 47553.0 47576.5 10449.6 10486.1 10653.1 10658.4 10680.6 7890.1 7944.7 7950.2 7953.7 7963.5 12113.4 12596.4 12659.9 13490.9 13671.2 5497.5 5546.3 5571.6 5581.4 5584.7 746.1 748.2 776.3 778.6 836.0 22208.6 24334.9 24570.1 24574.8 24668.5 868.4 871.9 880.6 890.3 969.8 13026.0 13041.5 13123.4 13207.5 13365.6 279488.0 281485.0 286122.0 289291.0 290051.0 36688.4 43543.0 43545.8 43558.3 43632.0 953.6 1048.6 1072.1 1085.0 1085.7 24380.8 25026.3 25941.0 25992.6 26049.0 37098.8 37158.4 37526.0 37542.8 37597.0 247701.0 248416.0 248929.8 275326.0 314967.0 20843.9 21044.6 21050.3 21062.9 21095.7 37505.2 37646.0 37685.8 37901.1 37944.4 31128.5 31647.0 32031.2 32284.4 32719.3 31911.8 32179.6 32270.7 32506.5 32535.3 29713.3 30461.4 30791.7 32394.7 33358.3 28532.4 29173.1 29235.7 29434.4 29610.4 22828.8 23071.5 23181.7 23299.8 23384.0
14:55:32 INFO - PROCESS | 1124 |
14:55:32 INFO - PROCESS | 1124 | [#2] jquery.html Cycles:180 Average:19852.13 Median:5072.04 stddev:46372.89 (914.3%) stddev-sans-first:46479.72
14:55:32 INFO - PROCESS | 1124 | Values: 289.3 320.5 323.1 325.0 329.6 11406.0 15384.4 15552.6 15814.2 16057.8 14905.6 15134.0 15670.6 15674.8 15682.1 4887.9 5068.5 5073.7 5083.2 5091.0 4826.2 4992.7 4994.2 5070.4 5076.5 4982.3 4994.7 5000.2 5003.3 5007.4 4977.1 4980.2 4980.7 4986.0 4989.0 4243.5 4401.6 4413.8 4433.9 4439.6 19484.7 19513.8 19706.6 19756.4 19793.7 1677.4 1701.8 1741.0 1765.4 1806.6 12291.3 12992.2 13153.4 13195.2 13343.2 1429.1 1530.1 1533.4 1571.7 1574.3 45035.8 59075.4 61617.7 64032.7 64438.0 8493.7 8754.6 8792.0 8911.3 8950.6 11389.7 11585.5 11627.9 11644.9 11645.7 6113.3 6128.4 6128.6 6229.7 6256.0 5632.2 5708.5 5824.2 5827.0 5836.3 5493.5 5579.7 5593.6 5598.9 5670.8 1581.0 1595.6 1602.0 1610.5 1622.5 623.9 814.2 826.9 857.5 891.1 16066.2 16259.6 16683.7 16809.4 17041.1 750.9 754.1 784.5 807.3 887.0 7311.3 8302.1 8702.0 9094.4 9159.3 270741.0 277978.0 277980.0 278303.0 279003.0 44865.6 71104.0 71815.3 74471.0 74808.6 828.6 859.0 859.8 860.8 863.5 14621.5 15025.8 15144.6 15236.5 15298.5 36202.8 36655.8 36928.6 37808.2 38029.4 49807.5 50152.5 50335.7 51072.5 52133.7 6089.9 6106.0 6230.6 6252.6 6338.6 7238.4 8724.6 9209.7 9233.4 9535.2 7062.3 7144.4 7150.3 7160.4 7175.9 7639.0 7741.4 7743.9 7840.7 7868.9 7229.4 7314.3 7343.6 7552.4 7799.7 6207.3 6214.8 6228.8 6236.4 6437.2 7096.0 7154.9 7209.9 7218.6 7376.3
14:55:32 INFO - PROCESS | 1124 |
14:55:32 INFO - PROCESS | 1124 | [#3] mootools.html Cycles:180 Average:4171.95 Median:5146.23 stddev:2278.24 (44.3%) stddev-sans-first:2273.50
14:55:32 INFO - PROCESS | 1124 | Values: 1175.6 1190.4 1264.7 1495.6 1503.3 5493.1 5704.9 6113.7 6750.1 6759.5 5609.0 5746.9 6415.1 6557.9 6570.9 1837.1 1850.2 1855.7 1857.0 1859.1 1712.2 1735.4 1762.7 1767.6 1773.1 1568.7 1618.8 1621.2 1632.3 1633.9 1769.2 1839.6 1840.7 1841.6 1842.2 1812.9 1822.1 1847.4 1853.3 1853.5 6240.9 6392.9 6504.1 6509.8 6514.1 3704.2 3805.1 3822.0 3828.2 3834.2 2506.2 2552.1 2555.5 2561.1 2563.1 830.6 831.6 870.1 871.1 876.9 8179.6 8314.6 8333.6 8363.0 8364.2 3306.4 3337.4 3355.5 3373.7 3414.9 7074.5 7333.7 7336.1 7345.6 7354.9 3684.5 3701.3 3742.0 3817.2 3893.0 3563.2 3578.8 3586.6 3587.7 3591.4 2928.4 2963.5 2988.7 3059.6 3066.3 977.0 999.1 1001.1 1007.6 1009.1 682.7 697.0 704.2 708.1 717.1 3725.6 4243.8 4396.4 4421.6 4625.1 735.4 764.9 793.6 797.2 808.6 2699.5 2765.9 3191.5 3226.0 3233.8 7200.7 7518.6 7598.5 7650.5 7693.5 7485.9 7696.2 7709.9 7715.0 7736.3 5951.5 5977.3 6119.1 6149.7 6149.7 4403.9 4493.0 4515.1 4534.0 4560.2 7136.9 7300.0 7380.3 7439.3 7471.8 7149.2 7225.6 7484.3 7501.3 7501.8 5404.5 5473.6 5489.9 5490.9 5503.0 5933.3 5950.1 5964.5 6166.2 6218.7 5247.2 5309.2 5363.3 5475.9 5575.3 4736.8 5103.9 5294.6 5308.1 5364.4 5118.3 5174.1 5186.2 5191.5 5195.5 3407.6 3527.2 3578.0 3585.7 3599.7 3521.6 3589.7 3640.8 3697.5 3711.7
14:55:32 INFO - PROCESS | 1124 |
14:55:32 INFO - PROCESS | 1124 | [#4] prototype.html Cycles:180 Average:3718.72 Median:4269.22 stddev:1132.24 (26.5%) stddev-sans-first:1104.67
14:55:32 INFO - PROCESS | 1124 | Values: 226.8 233.8 235.1 236.9 237.7 4328.7 4356.9 4365.3 4369.4 4371.9 4116.4 4167.9 4224.0 4225.5 4290.4 3114.6 3146.3 3176.0 3190.6 3205.2 3202.7 3205.8 3209.3 3209.4 3220.3 2952.2 3097.6 3107.2 3187.4 3194.0 3165.8 3180.4 3183.2 3183.6 3194.5 2768.2 2774.6 2775.0 2776.7 2780.6 4022.2 4179.4 4190.9 4193.1 4222.5 3713.7 3730.1 3739.3 3740.3 3741.5 4309.5 4345.0 4421.2 4429.4 4432.0 3113.1 3119.2 3124.1 3128.5 3131.0 4594.4 4637.0 4638.4 4639.2 4651.7 3176.3 3181.4 3181.9 3186.2 3197.3 3138.2 3149.8 3220.3 3224.1 3224.3 3597.2 3675.8 3703.7 3721.3 3725.9 4429.6 4459.4 4460.8 4471.8 4480.2 1097.0 1118.9 1120.7 1125.3 1126.4 693.9 717.1 722.6 747.8 772.4 4215.5 4246.4 4261.3 4263.4 4275.0 4479.4 4533.8 4545.6 4578.7 4584.8 4216.9 4232.8 4239.4 4241.5 4250.9 2328.7 2376.0 2395.6 2417.6 2426.6 4763.7 4769.9 4771.8 4784.9 4796.8 4677.8 4723.4 4733.6 4737.0 4740.3 4783.1 4786.8 4797.0 4797.4 4809.8 4395.7 4623.1 4634.4 4639.3 4640.5 4519.8 4547.7 4547.7 4557.0 4557.3 4770.4 4772.5 4786.4 4799.4 4808.3 3016.5 3044.3 3084.7 3095.9 3103.2 4518.3 4569.9 4582.8 4582.9 4598.8 4162.8 4373.5 4401.9 4416.5 4425.0 4350.9 4393.5 4416.3 4437.9 4441.9 4221.2 4323.3 4325.7 4343.6 4357.7 4447.3 4471.9 4478.4 4484.3 4487.7 4254.4 4405.3 4433.6 4455.7 4456.8
14:55:32 INFO - PROCESS | 1124 |
14:55:32 INFO - PROCESS | 1124 | [#5] yui.html Cycles:180 Average:16021.63 Median:487.81 stddev:22566.19 (4626.1%) stddev-sans-first:22599.64
14:55:32 INFO - PROCESS | 1124 | Values: 564.9 589.4 597.4 607.3 649.2 11810.4 12559.8 12649.6 12979.1 12980.8 12379.1 12475.9 12481.3 12489.6 12513.1 507.4 518.4 641.7 644.3 656.3 630.0 645.8 651.1 652.1 652.8 670.4 684.8 685.1 685.6 686.4 530.9 532.8 541.7 681.1 683.1 402.6 403.5 494.9 496.1 500.3 11222.9 11295.0 11443.0 11551.9 11566.4 11528.3 11571.8 11724.1 11788.5 11843.3 11080.3 11081.5 11154.8 11155.0 11248.2 4031.3 4082.1 4126.7 4210.3 4243.9 62834.7 63259.4 64272.4 64559.4 65748.7 8824.5 8875.3 9040.9 9184.6 9273.4 16224.5 16302.7 16377.3 16495.0 16593.2 6114.9 6128.4 6211.8 6234.8 6270.4 3348.2 3377.5 3397.4 3405.8 3430.5 2343.9 2387.6 2428.8 2455.7 2459.5 422.4 423.9 431.7 436.6 437.5 472.3 492.7 506.7 521.4 545.4 7210.0 7322.3 7340.2 7524.5 7661.8 468.8 482.9 558.7 562.7 584.0 3636.6 3639.7 3660.0 3686.7 3772.3 76115.0 81626.0 91134.0 94533.0 95515.0 64137.7 67516.0 70077.6 72948.5 73527.6 44168.8 44748.8 67396.0 70071.0 70174.3 7330.6 7545.0 7799.9 7803.3 7966.9 39778.4 40293.4 42267.8 42422.0 43628.8 56490.0 57167.0 57230.4 57238.0 60023.4 10444.7 10654.6 10661.3 10753.7 10765.2 10794.6 11085.5 11134.3 11140.6 11253.7 10621.7 10833.6 10857.0 10858.2 10943.5 7357.3 10317.3 10554.4 10626.5 10897.9 10543.5 10691.7 10706.4 10743.5 10878.9 8919.8 9602.8 9620.7 9740.8 9876.5 8821.5 8926.8 8991.0 9377.3 9405.2
14:55:32 INFO - PROCESS | 1124 | -------- Summary: end --------
14:55:32 INFO - PROCESS | 1124 |
14:55:33 INFO - TEST-INFO | 1124: exit 0
14:55:34 INFO - TEST-OK | dromaeo_css | took 1123959ms
14:55:34 INFO - TEST-START | kraken
14:55:34 INFO - operating with platform_type : w7_
14:55:34 INFO - Initialising browser for kraken test...
14:55:34 INFO - TEST-INFO | started process 1184 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld~1.t-w\appdata\local\temp\tmpvst6pr\profile http://localhost:49321/getInfo.html)
14:55:39 INFO - PROCESS | 1184 | __metrics Screen width/height:1600/1200
14:55:39 INFO - PROCESS | 1184 | colorDepth:24
14:55:39 INFO - PROCESS | 1184 | Browser inner width/height: 1010/674
14:55:39 INFO - PROCESS | 1184 | __metrics
14:55:40 INFO - PROCESS | 1184 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows"
14:55:40 INFO - PROCESS | 1184 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
14:55:41 INFO - PROCESS | 1184 | console.error:
14:55:41 INFO - PROCESS | 1184 | Message: Error: Connection closed before committing the transaction.
14:55:41 INFO - PROCESS | 1184 | Stack:
14:55:41 INFO - PROCESS | 1184 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:937:23
14:55:41 INFO - PROCESS | 1184 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7
14:55:41 INFO - PROCESS | 1184 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11
14:55:41 INFO - PROCESS | 1184 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7
14:55:41 INFO - PROCESS | 1184 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7
14:55:41 INFO - PROCESS | 1184 | TaskImpl_run@resource://gre/modules/Task.jsm:324:13
14:55:41 INFO - PROCESS | 1184 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9
14:55:41 INFO - PROCESS | 1184 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15
14:55:41 INFO - PROCESS | 1184 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5
14:55:41 INFO - PROCESS | 1184 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29
14:55:41 INFO - PROCESS | 1184 | Barrier/this.client.addBlocker/promise13.1%
14:56:42 INFO - TinderboxPrint: I/O read bytes / time
6,410,240 / 314,870
14:56:42 INFO - TinderboxPrint: I/O write bytes / time
453,224,960 / 3,263,980
14:56:42 INFO - TinderboxPrint: CPU idle
8,306.0 (86.9%)
14:56:42 INFO - TinderboxPrint: CPU user
1,220.2 (12.8%)
14:56:42 INFO - install - Wall time: 3s; CPU: 8.0%; Read bytes: 0; Write bytes: 74257920; Read time: 0; Write time: 537920
14:56:42 INFO - run-tests - Wall time: 1193s; CPU: 13.0%; Read bytes: 6251008; Write bytes: 377836544; Read time: 293590; Write time: 2725460
14:56:42 INFO - Running post-run listener: _upload_blobber_files
14:56:42 INFO - Blob upload gear active.
14:56:42 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir.
14:56:42 INFO - Files from C:\slave\test\build\blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com
14:56:42 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\venv\\Scripts\\blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', 'C:\\slave\\test\\oauth.txt', '-b', 'Autoland-Non-PGO', '-d', 'C:\\slave\\test\\build\\blobber_upload_dir', '--output-manifest', 'C:\\slave\\test\\build\\uploaded_files.json']
14:56:42 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\venv\Scripts\blobberc.py -u https://blobupload.elasticbeanstalk.com -a C:\slave\test\oauth.txt -b Autoland-Non-PGO -d C:\slave\test\build\blobber_upload_dir --output-manifest C:\slave\test\build\uploaded_files.json
14:56:43 INFO - (blobuploader) - INFO - Open directory for files ...
14:56:43 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log ...
14:56:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
14:56:43 INFO - (blobuploader) - INFO - Uploading, attempt #1.
14:56:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded
14:56:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
14:56:43 INFO - (blobuploader) - INFO - Done attempting.
14:56:43 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log ...
14:56:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
14:56:43 INFO - (blobuploader) - INFO - Uploading, attempt #1.
14:56:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded
14:56:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
14:56:44 INFO - (blobuploader) - INFO - Done attempting.
14:56:44 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ...
14:56:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
14:56:44 INFO - (blobuploader) - INFO - Uploading, attempt #1.
14:56:53 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
14:56:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
14:56:53 INFO - (blobuploader) - INFO - Done attempting.
14:56:53 INFO - (blobuploader) - INFO - Iteration through files over.
14:56:53 INFO - Return code: 0
14:56:53 INFO - rmtree: C:\slave\test\build\uploaded_files.json
14:56:53 INFO - Using _rmtree_windows ...
14:56:53 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1
14:56:53 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/6e556e6dddb0964ef724c069c8d15d15439244ae3eca7484f27e38f2f120604cced53e6f178f7c734e70346733c76951ee672cc87711a7678b7ab6bfb71df3b0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/6fe65d7794facc9adc9e563d16b9ab3cfff080f46186deea6c75c141a3dd420c250c88154868ebbe40ddadf2940721d9d5c67b16ed84be1934dd0b2a73172f82", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/a015a6aee6937bbc2118129a1608e0bf74879436d9c95aec477d75743e5abbb91f1f98d8b95f081d9b1c4e85427d4662cd9bedd0c8be90487bdfdb1ed9835058"}
14:56:53 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files
14:56:53 INFO - Writing to file C:\slave\test\properties\blobber_files
14:56:53 INFO - Contents:
14:56:53 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/6e556e6dddb0964ef724c069c8d15d15439244ae3eca7484f27e38f2f120604cced53e6f178f7c734e70346733c76951ee672cc87711a7678b7ab6bfb71df3b0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/6fe65d7794facc9adc9e563d16b9ab3cfff080f46186deea6c75c141a3dd420c250c88154868ebbe40ddadf2940721d9d5c67b16ed84be1934dd0b2a73172f82", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/a015a6aee6937bbc2118129a1608e0bf74879436d9c95aec477d75743e5abbb91f1f98d8b95f081d9b1c4e85427d4662cd9bedd0c8be90487bdfdb1ed9835058"}
14:56:53 INFO - Running post-run listener: copy_logs_to_upload_dir
14:56:53 INFO - Copying logs to upload dir...
14:56:53 INFO - mkdir: C:\slave\test\build\upload\logs
14:56:53 INFO - Copying logs to upload dir...
14:56:53 INFO - Using _rmtree_windows ...
14:56:53 INFO - Using _rmtree_windows ...
14:56:53 INFO - Using _rmtree_windows ...
14:56:53 INFO - Using _rmtree_windows ...
14:56:53 INFO - Using _rmtree_windows ...
14:56:53 INFO - Using _rmtree_windows ...
14:56:53 INFO - Using _rmtree_windows ...
program finished with exit code 0
elapsedTime=1380.797000
========= master_lag: 0.03 =========
========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 0 secs) (at 2016-09-28 14:56:53.442162) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-28 14:56:53.442890) =========
'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.T-W732-IX-117\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-117
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.T-W732-IX-117
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOCALAPPDATA=C:\Users\cltbld.T-W732-IX-117\AppData\Local
LOGONSERVER=\\T-W732-IX-117
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~1.T-W\AppData\Local\Temp
TEST1=testie
TMP=C:\Users\CLTBLD~1.T-W\AppData\Local\Temp
USERDOMAIN=T-W732-IX-117
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld.T-W732-IX-117
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/6e556e6dddb0964ef724c069c8d15d15439244ae3eca7484f27e38f2f120604cced53e6f178f7c734e70346733c76951ee672cc87711a7678b7ab6bfb71df3b0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/6fe65d7794facc9adc9e563d16b9ab3cfff080f46186deea6c75c141a3dd420c250c88154868ebbe40ddadf2940721d9d5c67b16ed84be1934dd0b2a73172f82", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/a015a6aee6937bbc2118129a1608e0bf74879436d9c95aec477d75743e5abbb91f1f98d8b95f081d9b1c4e85427d4662cd9bedd0c8be90487bdfdb1ed9835058"}
build_url:https://queue.taskcluster.net/v1/task/ff45gAeUSMmiMPeyn1GlEA/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
program finished with exit code 0
elapsedTime=0.101000
build_url: 'https://queue.taskcluster.net/v1/task/ff45gAeUSMmiMPeyn1GlEA/artifacts/public/build/firefox-52.0a1.en-US.win32.zip'
blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/6e556e6dddb0964ef724c069c8d15d15439244ae3eca7484f27e38f2f120604cced53e6f178f7c734e70346733c76951ee672cc87711a7678b7ab6bfb71df3b0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/6fe65d7794facc9adc9e563d16b9ab3cfff080f46186deea6c75c141a3dd420c250c88154868ebbe40ddadf2940721d9d5c67b16ed84be1934dd0b2a73172f82", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/a015a6aee6937bbc2118129a1608e0bf74879436d9c95aec477d75743e5abbb91f1f98d8b95f081d9b1c4e85427d4662cd9bedd0c8be90487bdfdb1ed9835058"}'
========= master_lag: 0.01 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-28 14:56:53.556066) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 14:56:53.556380) =========
'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.T-W732-IX-117\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-117
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.T-W732-IX-117
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOCALAPPDATA=C:\Users\cltbld.T-W732-IX-117\AppData\Local
LOGONSERVER=\\T-W732-IX-117
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~1.T-W\AppData\Local\Temp
TEST1=testie
TMP=C:\Users\CLTBLD~1.T-W\AppData\Local\Temp
USERDOMAIN=T-W732-IX-117
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld.T-W732-IX-117
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
program finished with exit code 0
elapsedTime=0.101000
========= master_lag: 0.01 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 14:56:53.670838) =========
========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-09-28 14:56:53.671481) =========
========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-09-28 14:56:54.242036) =========
========= Total master_lag: 0.24 =========