builder: fx-team_win7_ix_test-dromaeojs-pgo
slave: t-w732-ix-066
starttime: 1477007829.34
results: success (0)
buildid: 20161020162149
builduid: b20fc06acdd1458ebfce6f78c4a27536
revision: 4be40c5468626cd5065cbc79674c933b908a2450
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-20 16:57:09.336616) =========
master: http://buildbot-master110.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-20 16:57:09.337064) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-20 16:57:09.337356) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-20 16:57:09.540977) =========
========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-20 16:57:09.541293) =========
'bash' '-c' 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'
in dir C:\slave\test\. (timeout 1200 secs)
watching logfiles {}
argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-066
COMSPEC=C:\windows\system32\cmd.exe
CYGWINBASE=C:\cygwin
DCLOCATION=SCL3
DNSSUFFIX=wintest.releng.scl3.mozilla.com
FP_NO_HOST_CHECK=NO
HOMEDRIVE=C:
HOMEPATH=\Users\cltbld
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOCALAPPDATA=C:\Users\cltbld\AppData\Local
LOGONSERVER=\\T-W732-IX-066
MONDIR=C:\Monitor_config\
MOZBUILDDIR=C:\mozilla-build\
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NO_EM_RESTART=1
NUMBER_OF_PROCESSORS=8
OS=Windows_NT
OURDRIVE=C:
PATH=C:\Python24;C:\Python24\Scripts;C:\cygwin\bin;C:\WINDOWS\System32;C:\program files\gnuwin32\bin;C:\WINDOWS;C:\mozilla-build\msys\bin;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\info-zip;C:\mozilla-build\wget;C:\Program Files (x86)\GnuWin32\bin;
PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC
PROCESSOR_ARCHITECTURE=x86
PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel
PROCESSOR_LEVEL=6
PROCESSOR_REVISION=1e05
PROGRAMDATA=C:\ProgramData
PROGRAMFILES=C:\Program Files
PROMPT=$P$G
PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\
PUBLIC=C:\Users\Public
PWD=C:\slave\test
RUNLOGFILE=C:\slave\\runslave.log
SLAVEDIR=C:\slave\
SYSTEMDRIVE=C:
SYSTEMROOT=C:\windows
TEMP=C:\Users\cltbld\AppData\Local\Temp
TEST1=testie
TMP=C:\Users\cltbld\AppData\Local\Temp
USERDOMAIN=T-W732-IX-066
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
--16:57:11-- 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,179 (12K) [text/x-python]
0K .......... . 100% 7.95 MB/s
16:57:11 (7.95 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=1.853000
========= master_lag: 0.29 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-20 16:57:11.682363) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 15 secs) (at 2016-10-20 16:57:11.682870) =========
'rm' '-rf' 'scripts' 'properties'
in dir C:\slave\test\. (timeout 1200 secs)
watching logfiles {}
argv: ['rm', '-rf', 'scripts', 'properties']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-066
COMSPEC=C:\windows\system32\cmd.exe
CYGWINBASE=C:\cygwin
DCLOCATION=SCL3
DNSSUFFIX=wintest.releng.scl3.mozilla.com
FP_NO_HOST_CHECK=NO
HOMEDRIVE=C:
HOMEPATH=\Users\cltbld
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOCALAPPDATA=C:\Users\cltbld\AppData\Local
LOGONSERVER=\\T-W732-IX-066
MONDIR=C:\Monitor_config\
MOZBUILDDIR=C:\mozilla-build\
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NO_EM_RESTART=1
NUMBER_OF_PROCESSORS=8
OS=Windows_NT
OURDRIVE=C:
PATH=C:\Python24;C:\Python24\Scripts;C:\cygwin\bin;C:\WINDOWS\System32;C:\program files\gnuwin32\bin;C:\WINDOWS;C:\mozilla-build\msys\bin;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\info-zip;C:\mozilla-build\wget;C:\Program Files (x86)\GnuWin32\bin;
PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC
PROCESSOR_ARCHITECTURE=x86
PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel
PROCESSOR_LEVEL=6
PROCESSOR_REVISION=1e05
PROGRAMDATA=C:\ProgramData
PROGRAMFILES=C:\Program Files
PROMPT=$P$G
PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\
PUBLIC=C:\Users\Public
PWD=C:\slave\test
RUNLOGFILE=C:\slave\\runslave.log
SLAVEDIR=C:\slave\
SYSTEMDRIVE=C:
SYSTEMROOT=C:\windows
TEMP=C:\Users\cltbld\AppData\Local\Temp
TEST1=testie
TMP=C:\Users\cltbld\AppData\Local\Temp
USERDOMAIN=T-W732-IX-066
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
program finished with exit code 0
elapsedTime=15.201000
========= master_lag: 0.15 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 15 secs) (at 2016-10-20 16:57:27.030982) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-20 16:57:27.031364) =========
'bash' '-c' u'python archiver_client.py mozharness --repo integration/fx-team --rev 4be40c5468626cd5065cbc79674c933b908a2450 --destination scripts --debug'
in dir C:\slave\test\. (timeout 1200 secs)
watching logfiles {}
argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 4be40c5468626cd5065cbc79674c933b908a2450 --destination scripts --debug']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-066
COMSPEC=C:\windows\system32\cmd.exe
CYGWINBASE=C:\cygwin
DCLOCATION=SCL3
DNSSUFFIX=wintest.releng.scl3.mozilla.com
FP_NO_HOST_CHECK=NO
HOMEDRIVE=C:
HOMEPATH=\Users\cltbld
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOCALAPPDATA=C:\Users\cltbld\AppData\Local
LOGONSERVER=\\T-W732-IX-066
MONDIR=C:\Monitor_config\
MOZBUILDDIR=C:\mozilla-build\
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NO_EM_RESTART=1
NUMBER_OF_PROCESSORS=8
OS=Windows_NT
OURDRIVE=C:
PATH=C:\Python24;C:\Python24\Scripts;C:\cygwin\bin;C:\WINDOWS\System32;C:\program files\gnuwin32\bin;C:\WINDOWS;C:\mozilla-build\msys\bin;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\info-zip;C:\mozilla-build\wget;C:\Program Files (x86)\GnuWin32\bin;
PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC
PROCESSOR_ARCHITECTURE=x86
PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel
PROCESSOR_LEVEL=6
PROCESSOR_REVISION=1e05
PROGRAMDATA=C:\ProgramData
PROGRAMFILES=C:\Program Files
PROMPT=$P$G
PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\
PUBLIC=C:\Users\Public
PWD=C:\slave\test
RUNLOGFILE=C:\slave\\runslave.log
SLAVEDIR=C:\slave\
SYSTEMDRIVE=C:
SYSTEMROOT=C:\windows
TEMP=C:\Users\cltbld\AppData\Local\Temp
TEST1=testie
TMP=C:\Users\cltbld\AppData\Local\Temp
USERDOMAIN=T-W732-IX-066
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
2016-10-20 16:57:27,765 truncating revision to first 12 chars
2016-10-20 16:57:27,765 Setting DEBUG logging.
2016-10-20 16:57:27,765 attempt 1/10
2016-10-20 16:57:27,765 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/4be40c546862?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2016-10-20 16:57:28,279 unpacking tar archive at: fx-team-4be40c546862/testing/mozharness/
2016-10-20 16:57:28,400 skipping symlink on windows: configs/single_locale/linux32.py
program finished with exit code 0
elapsedTime=1.329000
========= master_lag: 0.05 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-20 16:57:28.414253) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-20 16:57:28.414576) =========
script_repo_revision: 4be40c5468626cd5065cbc79674c933b908a2450
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-20 16:57:28.414965) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-20 16:57:28.415244) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-20 16:57:28.425446) =========
========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 24 secs) (at 2016-10-20 16:57:28.425794) =========
'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Fx-Team' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Fx-Team'
in dir C:\slave\test\. (timeout 3600 secs) (maxTime 7200 secs)
watching logfiles {}
argv: ['c:/mozilla-build/python27/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-066
COMSPEC=C:\windows\system32\cmd.exe
CYGWINBASE=C:\cygwin
DCLOCATION=SCL3
DNSSUFFIX=wintest.releng.scl3.mozilla.com
FP_NO_HOST_CHECK=NO
HOMEDRIVE=C:
HOMEPATH=\Users\cltbld
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOCALAPPDATA=C:\Users\cltbld\AppData\Local
LOGONSERVER=\\T-W732-IX-066
MONDIR=C:\Monitor_config\
MOZBUILDDIR=C:\mozilla-build\
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NO_EM_RESTART=1
NUMBER_OF_PROCESSORS=8
OS=Windows_NT
OURDRIVE=C:
PATH=C:\Python24;C:\Python24\Scripts;C:\cygwin\bin;C:\WINDOWS\System32;C:\program files\gnuwin32\bin;C:\WINDOWS;C:\mozilla-build\msys\bin;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\info-zip;C:\mozilla-build\wget;C:\Program Files (x86)\GnuWin32\bin;
PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC
PROCESSOR_ARCHITECTURE=x86
PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel
PROCESSOR_LEVEL=6
PROCESSOR_REVISION=1e05
PROGRAMDATA=C:\ProgramData
PROGRAMFILES=C:\Program Files
PROMPT=$P$G
PROPERTIES_FILE=C:\slave\test/buildprops.json
PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\
PUBLIC=C:\Users\Public
PWD=C:\slave\test
RUNLOGFILE=C:\slave\\runslave.log
SLAVEDIR=C:\slave\
SYSTEMDRIVE=C:
SYSTEMROOT=C:\windows
TEMP=C:\Users\cltbld\AppData\Local\Temp
TEST1=testie
TMP=C:\Users\cltbld\AppData\Local\Temp
USERDOMAIN=T-W732-IX-066
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
16:57:29 INFO - MultiFileLogger online at 20161020 16:57:29 in C:\slave\test
16:57:29 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team
16:57:29 INFO - Dumping config to C:\slave\test\logs\localconfig.json.
16:57:29 INFO - {'append_to_log': False,
16:57:29 INFO - 'base_work_dir': 'C:\\slave\\test',
16:57:29 INFO - 'blob_upload_branch': 'Fx-Team',
16:57:29 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt',
16:57:29 INFO - 'branch': 'Fx-Team',
16:57:29 INFO - 'buildbot_json_path': 'buildprops.json',
16:57:29 INFO - 'config_files': ('talos/windows_config.py',),
16:57:29 INFO - 'default_actions': ('clobber',
16:57:29 INFO - 'read-buildbot-config',
16:57:29 INFO - 'download-and-extract',
16:57:29 INFO - 'populate-webroot',
16:57:29 INFO - 'create-virtualenv',
16:57:29 INFO - 'install',
16:57:29 INFO - 'run-tests'),
16:57:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
16:57:29 INFO - 'download_minidump_stackwalk': True,
16:57:29 INFO - 'download_symbols': 'ondemand',
16:57:29 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python',
16:57:29 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'),
16:57:29 INFO - 'hg': 'c:/mozilla-build/hg/hg',
16:57:29 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python',
16:57:29 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'),
16:57:29 INFO - 'python': 'c:/mozilla-build/python27/python.exe',
16:57:29 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe',
16:57:29 INFO - 'C:/mozilla-build/tooltool.py'),
16:57:29 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe',
16:57:29 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')},
16:57:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
16:57:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
16:57:29 INFO - 'installer_path': 'installer.exe',
16:57:29 INFO - 'log_level': 'info',
16:57:29 INFO - 'log_name': 'talos',
16:57:29 INFO - 'log_to_console': True,
16:57:29 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe',
16:57:29 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe',
16:57:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest',
16:57:29 INFO - 'opt_config_files': (),
16:57:29 INFO - 'pip_index': False,
16:57:29 INFO - 'sps_profile': False,
16:57:29 INFO - 'sps_profile_interval': 0,
16:57:29 INFO - 'suite': 'dromaeojs',
16:57:29 INFO - 'system_bits': '32',
16:57:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
16:57:29 INFO - 'title': 'T-W732-IX-066',
16:57:29 INFO - 'use_talos_json': True,
16:57:29 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'),
16:57:29 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv',
16:57:29 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll',
16:57:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
16:57:29 INFO - 'work_dir': 'build'}
16:57:29 INFO - [mozharness: 2016-10-20 23:57:29.301000Z] Running clobber step.
16:57:29 INFO - Running pre-action listener: _resource_record_pre_action
16:57:29 INFO - Running main action method: clobber
16:57:29 INFO - rmtree: C:\slave\test\build
16:57:29 INFO - Using _rmtree_windows ...
16:57:29 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1
16:58:57 INFO - Running post-action listener: _resource_record_post_action
16:58:57 INFO - [mozharness: 2016-10-20 23:58:57.975000Z] Finished clobber step (success)
16:58:57 INFO - [mozharness: 2016-10-20 23:58:57.975000Z] Running read-buildbot-config step.
16:58:57 INFO - Running pre-action listener: _resource_record_pre_action
16:58:57 INFO - Running main action method: read_buildbot_config
16:58:57 INFO - Using buildbot properties:
16:58:57 INFO - {
16:58:57 INFO - "project": "",
16:58:57 INFO - "product": "firefox",
16:58:57 INFO - "script_repo_revision": "production",
16:58:57 INFO - "builddir": "fx-team_win7_ix_test-dromaeojs",
16:58:57 INFO - "repository": "",
16:58:57 INFO - "buildername": "Windows 7 32-bit fx-team pgo talos dromaeojs",
16:58:57 INFO - "buildid": "20161020162149",
16:58:57 INFO - "stage_platform": "win32-pgo",
16:58:57 INFO - "basedir": "C:\\slave\\test",
16:58:57 INFO - "buildnumber": 241,
16:58:57 INFO - "platform": "win7_ix",
16:58:57 INFO - "master": "http://buildbot-master110.bb.releng.scl3.mozilla.com:8201/",
16:58:57 INFO - "slavebuilddir": "test",
16:58:57 INFO - "branch": "fx-team",
16:58:57 INFO - "revision": "4be40c5468626cd5065cbc79674c933b908a2450",
16:58:57 INFO - "slavename": "t-w732-ix-066",
16:58:57 INFO - "builduid": "b20fc06acdd1458ebfce6f78c4a27536",
16:58:57 INFO - "repo_path": "integration/fx-team"
16:58:57 INFO - }
16:58:57 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476992154/firefox-52.0a1.en-US.win32.test_packages.json.
16:58:57 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476992154/firefox-52.0a1.en-US.win32.zip.
16:58:57 INFO - Running post-action listener: _resource_record_post_action
16:58:57 INFO - [mozharness: 2016-10-20 23:58:57.978000Z] Finished read-buildbot-config step (success)
16:58:57 INFO - [mozharness: 2016-10-20 23:58:57.979000Z] Running download-and-extract step.
16:58:57 INFO - Running pre-action listener: _resource_record_pre_action
16:58:57 INFO - Running main action method: download_and_extract
16:58:57 INFO - mkdir: C:\slave\test\build\tests
16:58:57 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')]}
16:58:57 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476992154/firefox-52.0a1.en-US.win32.test_packages.json
16:58:57 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476992154/firefox-52.0a1.en-US.win32.test_packages.json to C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json
16:58:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476992154/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
16:58:58 INFO - Downloaded 1218 bytes.
16:58:58 INFO - Reading from file C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json
16:58:58 INFO - Using the following test package requirements:
16:58:58 INFO - {u'common': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
16:58:58 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
16:58:58 INFO - u'firefox-52.0a1.en-US.win32.cppunittest.tests.zip'],
16:58:58 INFO - u'gtest': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
16:58:58 INFO - u'jittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
16:58:58 INFO - u'jsshell-win32.zip'],
16:58:58 INFO - u'mochitest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
16:58:58 INFO - u'firefox-52.0a1.en-US.win32.mochitest.tests.zip'],
16:58:58 INFO - u'mozbase': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
16:58:58 INFO - u'reftest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
16:58:58 INFO - u'firefox-52.0a1.en-US.win32.reftest.tests.zip'],
16:58:58 INFO - u'talos': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
16:58:58 INFO - u'firefox-52.0a1.en-US.win32.talos.tests.zip'],
16:58:58 INFO - u'web-platform': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
16:58:58 INFO - u'firefox-52.0a1.en-US.win32.web-platform.tests.zip'],
16:58:58 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
16:58:58 INFO - u'firefox-52.0a1.en-US.win32.xpcshell.tests.zip']}
16:58:58 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip'] for test suite category: common
16:58:58 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476992154/firefox-52.0a1.en-US.win32.common.tests.zip
16:58:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476992154/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1
16:58:58 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476992154/firefox-52.0a1.en-US.win32.common.tests.zip into memory
16:58:58 INFO - Http code: 200
16:58:58 INFO - via: 1.1 bb8a7ff26b131d51953dc2d3e19a2751.cloudfront.net (CloudFront)
16:58:58 INFO - x-amz-cf-id: SDDZQUjAfRLSEmYvYIlKqbcRrc5FE62_IynNJkd2sUu9b9HwHgjDxA==
16:58:58 INFO - x-amz-replication-status: COMPLETED
16:58:58 INFO - x-amz-version-id: cTZZFYOy9SwoEB4Y3J4Ci4tcbPSVhUSP
16:58:59 INFO - Expected file size: 17265184
16:58:59 INFO - Obtained file size: 17265184
16:59:30 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
16:59:30 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476992154/firefox-52.0a1.en-US.win32.common.tests.zip
16:59:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476992154/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1
16:59:30 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476992154/firefox-52.0a1.en-US.win32.common.tests.zip into memory
16:59:30 INFO - Http code: 200
16:59:30 INFO - via: 1.1 5c2ff4ca1e447265402af29264e83497.cloudfront.net (CloudFront)
16:59:30 INFO - x-amz-cf-id: i-TXwuPGXozTddlSKGvXrW8I8bZEKTkgHwJp3ZJ25XMIs6hwfO5pgQ==
16:59:30 INFO - x-amz-replication-status: COMPLETED
16:59:30 INFO - x-amz-version-id: cTZZFYOy9SwoEB4Y3J4Ci4tcbPSVhUSP
16:59:30 INFO - Expected file size: 17265184
16:59:30 INFO - Obtained file size: 17265184
16:59:44 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476992154/firefox-52.0a1.en-US.win32.talos.tests.zip
16:59:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476992154/firefox-52.0a1.en-US.win32.talos.tests.zip'}, attempt #1
16:59:44 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476992154/firefox-52.0a1.en-US.win32.talos.tests.zip into memory
16:59:44 INFO - Http code: 200
16:59:44 INFO - via: 1.1 66ed69e8b47ad05050331602c798132f.cloudfront.net (CloudFront)
16:59:44 INFO - x-amz-cf-id: zX00F8GgEp0TfIgKbRSLJw6IHrkgK-7gnaq5QFGuQAECPUMAlqBANg==
16:59:44 INFO - x-amz-replication-status: COMPLETED
16:59:44 INFO - x-amz-version-id: p77Yjq.KeWVzIFCPMnnvKR29uzVsu4AN
16:59:45 INFO - Expected file size: 14409028
16:59:45 INFO - Obtained file size: 14409028
16:59:55 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')]}
16:59:55 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476992154/firefox-52.0a1.en-US.win32.zip
16:59:55 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476992154/firefox-52.0a1.en-US.win32.zip to C:\slave\test\build\firefox-52.0a1.en-US.win32.zip
16:59:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476992154/firefox-52.0a1.en-US.win32.zip', 'file_name': 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.zip'}, attempt #1
16:59:58 INFO - Downloaded 54323664 bytes.
16:59:58 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476992154/firefox-52.0a1.en-US.win32.zip
16:59:58 INFO - mkdir: C:\slave\test\properties
16:59:58 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url
16:59:58 INFO - Writing to file C:\slave\test\properties\build_url
16:59:58 INFO - Contents:
16:59:58 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476992154/firefox-52.0a1.en-US.win32.zip
16:59:59 INFO - Running post-action listener: _resource_record_post_action
16:59:59 INFO - Running post-action listener: set_extra_try_arguments
16:59:59 INFO - [mozharness: 2016-10-20 23:59:59.007000Z] Finished download-and-extract step (success)
16:59:59 INFO - [mozharness: 2016-10-20 23:59:59.007000Z] Running populate-webroot step.
16:59:59 INFO - Running pre-action listener: _resource_record_pre_action
16:59:59 INFO - Running main action method: populate_webroot
16:59:59 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']},
16:59:59 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']},
16:59:59 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome',
16:59:59 INFO - u'--tppagecycles',
16:59:59 INFO - u'7'],
16:59:59 INFO - u'tests': [u'tsvgm']}},
16:59:59 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'],
16:59:59 INFO - u'tests': [u'tresize', u'tcanvasmark']},
16:59:59 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']},
16:59:59 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'],
16:59:59 INFO - u'tests': [u'dromaeo_css', u'kraken']},
16:59:59 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']},
16:59:59 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
16:59:59 INFO - u'talos_options': [u'--disable-e10s'],
16:59:59 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
16:59:59 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
16:59:59 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
16:59:59 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
16:59:59 INFO - u'talos_options': [u'--disable-e10s'],
16:59:59 INFO - u'tests': [u'damp', u'tps']},
16:59:59 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
16:59:59 INFO - u'tests': [u'damp', u'tps']},
16:59:59 INFO - u'g3': {u'talos_options': [u'--disable-e10s'],
16:59:59 INFO - u'tests': [u'dromaeo_dom']},
16:59:59 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']},
16:59:59 INFO - u'g4': {u'talos_options': [u'--disable-e10s'],
16:59:59 INFO - u'tests': [u'basic_compositor_video']},
16:59:59 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']},
16:59:59 INFO - u'other': {u'talos_options': [u'--disable-e10s'],
16:59:59 INFO - u'tests': [u'a11yr',
16:59:59 INFO - u'ts_paint',
16:59:59 INFO - u'tpaint',
16:59:59 INFO - u'sessionrestore',
16:59:59 INFO - u'sessionrestore_no_auto_restore',
16:59:59 INFO - u'tabpaint']},
16:59:59 INFO - u'other-e10s': {u'tests': [u'a11yr',
16:59:59 INFO - u'ts_paint',
16:59:59 INFO - u'tpaint',
16:59:59 INFO - u'sessionrestore',
16:59:59 INFO - u'sessionrestore_no_auto_restore',
16:59:59 INFO - u'tabpaint']},
16:59:59 INFO - u'other-e10s_l64': {u'tests': [u'a11yr',
16:59:59 INFO - u'ts_paint',
16:59:59 INFO - u'tpaint',
16:59:59 INFO - u'sessionrestore',
16:59:59 INFO - u'sessionrestore_no_auto_restore',
16:59:59 INFO - u'tabpaint']},
16:59:59 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr',
16:59:59 INFO - u'ts_paint',
16:59:59 INFO - u'tpaint',
16:59:59 INFO - u'sessionrestore',
16:59:59 INFO - u'sessionrestore_no_auto_restore',
16:59:59 INFO - u'tabpaint']},
16:59:59 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'],
16:59:59 INFO - u'tests': [u'a11yr',
16:59:59 INFO - u'ts_paint',
16:59:59 INFO - u'tpaint',
16:59:59 INFO - u'sessionrestore',
16:59:59 INFO - u'sessionrestore_no_auto_restore',
16:59:59 INFO - u'tabpaint']},
16:59:59 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'],
16:59:59 INFO - u'tests': [u'a11yr',
16:59:59 INFO - u'ts_paint',
16:59:59 INFO - u'tpaint',
16:59:59 INFO - u'sessionrestore',
16:59:59 INFO - u'sessionrestore_no_auto_restore',
16:59:59 INFO - u'tabpaint']},
16:59:59 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'],
16:59:59 INFO - u'tests': [u'tsvgx',
16:59:59 INFO - u'tsvgr_opacity',
16:59:59 INFO - u'tart',
16:59:59 INFO - u'tscrollx',
16:59:59 INFO - u'cart']},
16:59:59 INFO - u'svgr-e10s': {u'tests': [u'tsvgx',
16:59:59 INFO - u'tsvgr_opacity',
16:59:59 INFO - u'tart',
16:59:59 INFO - u'tscrollx',
16:59:59 INFO - u'cart']},
16:59:59 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
16:59:59 INFO - u'talos_options': [u'--disable-e10s'],
16:59:59 INFO - u'tests': [u'tp5o']},
16:59:59 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
16:59:59 INFO - u'tests': [u'tp5o']},
16:59:59 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
16:59:59 INFO - u'talos_options': [u'--disable-e10s',
16:59:59 INFO - u'--xperf_path',
16:59:59 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
16:59:59 INFO - u'tests': [u'tp5n']},
16:59:59 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
16:59:59 INFO - u'talos_options': [u'--xperf_path',
16:59:59 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
16:59:59 INFO - u'tests': [u'tp5n']}},
16:59:59 INFO - u'talos.zip': {u'path': u'',
16:59:59 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}}
16:59:59 INFO - Running post-action listener: _resource_record_post_action
16:59:59 INFO - [mozharness: 2016-10-20 23:59:59.049000Z] Finished populate-webroot step (success)
16:59:59 INFO - [mozharness: 2016-10-20 23:59:59.049000Z] Running create-virtualenv step.
16:59:59 INFO - Running pre-action listener: _resource_record_pre_action
16:59:59 INFO - Running main action method: create_virtualenv
16:59:59 INFO - Creating virtualenv C:\slave\test\build/venv
16:59:59 INFO - mkdir: C:\slave\test\build\venv\Scripts
16:59:59 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll
16:59:59 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'!
16:59:59 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
16:59:59 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv
17:00:11 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe
17:00:14 INFO - Installing distribute......................................................................................................................................................................................done.
17:00:16 INFO - Return code: 0
17:00:16 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version']
17:00:16 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version
17:00:16 INFO - Reading from file tmpfile_stdout
17:00:16 INFO - Output received:
17:00:16 INFO - pip 0.8.2 from c:\slave\test\build\venv\lib\site-packages\pip-0.8.2-py2.7.egg (python 2.7)
17:00:16 INFO - Using _rmtree_windows ...
17:00:16 INFO - Using _rmtree_windows ...
17:00:16 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv
17:00:16 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')]}
17:00:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
17:00:16 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')]}
17:00:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
17:00:16 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017E1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01885E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0178B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01892E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012AA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01954520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-066', 'USERDOMAIN': 'T-W732-IX-066', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-066', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
17:00:16 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
17:00:16 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
17:00:16 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
17:00:16 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
17:00:16 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
17:00:16 INFO - 'COMPUTERNAME': 'T-W732-IX-066',
17:00:16 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
17:00:16 INFO - 'CYGWINBASE': 'C:\\cygwin',
17:00:16 INFO - 'DCLOCATION': 'SCL3',
17:00:16 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
17:00:16 INFO - 'FP_NO_HOST_CHECK': 'NO',
17:00:16 INFO - 'HOMEDRIVE': 'C:',
17:00:16 INFO - 'HOMEPATH': '\\Users\\cltbld',
17:00:16 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
17:00:16 INFO - 'KTS_VERSION': '1.19c',
17:00:16 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
17:00:16 INFO - 'LOGONSERVER': '\\\\T-W732-IX-066',
17:00:16 INFO - 'MONDIR': 'C:\\Monitor_config\\',
17:00:16 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
17:00:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
17:00:16 INFO - 'MOZ_NO_REMOTE': '1',
17:00:16 INFO - 'NO_EM_RESTART': '1',
17:00:16 INFO - 'NUMBER_OF_PROCESSORS': '8',
17:00:16 INFO - 'OS': 'Windows_NT',
17:00:16 INFO - 'OURDRIVE': 'C:',
17:00:16 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;',
17:00:16 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
17:00:16 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
17:00:16 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
17:00:16 INFO - 'PROCESSOR_LEVEL': '6',
17:00:16 INFO - 'PROCESSOR_REVISION': '1e05',
17:00:16 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
17:00:16 INFO - 'PROGRAMFILES': 'C:\\Program Files',
17:00:16 INFO - 'PROMPT': '$P$G',
17:00:16 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
17:00:16 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
17:00:16 INFO - 'PUBLIC': 'C:\\Users\\Public',
17:00:16 INFO - 'PWD': 'C:\\slave\\test',
17:00:16 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
17:00:16 INFO - 'SLAVEDIR': 'C:\\slave\\',
17:00:16 INFO - 'SYSTEMDRIVE': 'C:',
17:00:16 INFO - 'SYSTEMROOT': 'C:\\windows',
17:00:16 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
17:00:16 INFO - 'TEST1': 'testie',
17:00:16 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
17:00:16 INFO - 'USERDOMAIN': 'T-W732-IX-066',
17:00:16 INFO - 'USERNAME': 'cltbld',
17:00:16 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
17:00:16 INFO - 'WINDIR': 'C:\\windows',
17:00:16 INFO - 'WINDOWS_TRACING_FLAGS': '3',
17:00:16 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
17:00:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
17:00:17 INFO - Ignoring indexes: http://pypi.python.org/simple/
17:00:17 INFO - Downloading/unpacking pip>=1.5
17:00:21 INFO - Running setup.py egg_info for package pip
17:00:21 INFO - warning: no files found matching 'pip\cacert.pem'
17:00:21 INFO - warning: no files found matching '*.html' under directory 'docs'
17:00:21 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
17:00:21 INFO - no previously-included directories found matching 'docs\_build\_sources'
17:00:21 INFO - Installing collected packages: pip
17:00:21 INFO - Found existing installation: pip 0.8.2
17:00:21 INFO - Uninstalling pip:
17:00:21 INFO - Successfully uninstalled pip
17:00:21 INFO - Running setup.py install for pip
17:00:21 INFO - warning: no files found matching 'pip\cacert.pem'
17:00:21 INFO - warning: no files found matching '*.html' under directory 'docs'
17:00:21 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
17:00:21 INFO - no previously-included directories found matching 'docs\_build\_sources'
17:00:21 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts
17:00:21 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts
17:00:21 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts
17:00:21 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts
17:00:21 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts
17:00:21 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts
17:00:21 INFO - Successfully installed pip
17:00:21 INFO - Cleaning up...
17:00:21 INFO - Return code: 0
17:00:21 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv
17:00:21 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')]}
17:00:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
17:00:21 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')]}
17:00:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
17:00:21 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017E1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01885E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0178B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01892E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012AA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01954520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-066', 'USERDOMAIN': 'T-W732-IX-066', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-066', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
17:00:21 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in C:\slave\test\build
17:00:21 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1
17:00:21 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
17:00:21 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
17:00:21 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
17:00:21 INFO - 'COMPUTERNAME': 'T-W732-IX-066',
17:00:21 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
17:00:21 INFO - 'CYGWINBASE': 'C:\\cygwin',
17:00:21 INFO - 'DCLOCATION': 'SCL3',
17:00:21 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
17:00:21 INFO - 'FP_NO_HOST_CHECK': 'NO',
17:00:21 INFO - 'HOMEDRIVE': 'C:',
17:00:21 INFO - 'HOMEPATH': '\\Users\\cltbld',
17:00:21 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
17:00:21 INFO - 'KTS_VERSION': '1.19c',
17:00:21 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
17:00:21 INFO - 'LOGONSERVER': '\\\\T-W732-IX-066',
17:00:21 INFO - 'MONDIR': 'C:\\Monitor_config\\',
17:00:21 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
17:00:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
17:00:21 INFO - 'MOZ_NO_REMOTE': '1',
17:00:21 INFO - 'NO_EM_RESTART': '1',
17:00:21 INFO - 'NUMBER_OF_PROCESSORS': '8',
17:00:21 INFO - 'OS': 'Windows_NT',
17:00:21 INFO - 'OURDRIVE': 'C:',
17:00:21 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
17:00:21 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
17:00:21 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
17:00:21 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
17:00:21 INFO - 'PROCESSOR_LEVEL': '6',
17:00:21 INFO - 'PROCESSOR_REVISION': '1e05',
17:00:21 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
17:00:21 INFO - 'PROGRAMFILES': 'C:\\Program Files',
17:00:21 INFO - 'PROMPT': '$P$G',
17:00:21 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
17:00:21 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
17:00:21 INFO - 'PUBLIC': 'C:\\Users\\Public',
17:00:21 INFO - 'PWD': 'C:\\slave\\test',
17:00:21 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
17:00:21 INFO - 'SLAVEDIR': 'C:\\slave\\',
17:00:21 INFO - 'SYSTEMDRIVE': 'C:',
17:00:21 INFO - 'SYSTEMROOT': 'C:\\windows',
17:00:21 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
17:00:21 INFO - 'TEST1': 'testie',
17:00:21 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
17:00:21 INFO - 'USERDOMAIN': 'T-W732-IX-066',
17:00:21 INFO - 'USERNAME': 'cltbld',
17:00:21 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
17:00:21 INFO - 'WINDIR': 'C:\\windows',
17:00:21 INFO - 'WINDOWS_TRACING_FLAGS': '3',
17:00:21 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
17:00:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
17:00:23 INFO - Ignoring indexes: https://pypi.python.org/simple/
17:00:23 INFO - Downloading/unpacking psutil>=3.1.1
17:00:23 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
17:00:23 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
17:00:24 INFO - Installing collected packages: psutil
17:00:24 INFO - Successfully installed psutil
17:00:24 INFO - Cleaning up...
17:00:25 INFO - Return code: 0
17:00:25 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv
17:00: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')]}
17:00:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
17:00: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')]}
17:00:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
17:00:25 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017E1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01885E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0178B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01892E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012AA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01954520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-066', 'USERDOMAIN': 'T-W732-IX-066', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-066', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
17:00:25 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
17:00:25 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
17:00:25 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
17:00:25 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
17:00:25 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
17:00:25 INFO - 'COMPUTERNAME': 'T-W732-IX-066',
17:00:25 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
17:00:25 INFO - 'CYGWINBASE': 'C:\\cygwin',
17:00:25 INFO - 'DCLOCATION': 'SCL3',
17:00:25 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
17:00:25 INFO - 'FP_NO_HOST_CHECK': 'NO',
17:00:25 INFO - 'HOMEDRIVE': 'C:',
17:00:25 INFO - 'HOMEPATH': '\\Users\\cltbld',
17:00:25 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
17:00:25 INFO - 'KTS_VERSION': '1.19c',
17:00:25 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
17:00:25 INFO - 'LOGONSERVER': '\\\\T-W732-IX-066',
17:00:25 INFO - 'MONDIR': 'C:\\Monitor_config\\',
17:00:25 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
17:00:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
17:00:25 INFO - 'MOZ_NO_REMOTE': '1',
17:00:25 INFO - 'NO_EM_RESTART': '1',
17:00:25 INFO - 'NUMBER_OF_PROCESSORS': '8',
17:00:25 INFO - 'OS': 'Windows_NT',
17:00:25 INFO - 'OURDRIVE': 'C:',
17:00: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;',
17:00:25 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
17:00:25 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
17:00:25 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
17:00:25 INFO - 'PROCESSOR_LEVEL': '6',
17:00:25 INFO - 'PROCESSOR_REVISION': '1e05',
17:00:25 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
17:00:25 INFO - 'PROGRAMFILES': 'C:\\Program Files',
17:00:25 INFO - 'PROMPT': '$P$G',
17:00:25 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
17:00:25 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
17:00:25 INFO - 'PUBLIC': 'C:\\Users\\Public',
17:00:25 INFO - 'PWD': 'C:\\slave\\test',
17:00:25 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
17:00:25 INFO - 'SLAVEDIR': 'C:\\slave\\',
17:00:25 INFO - 'SYSTEMDRIVE': 'C:',
17:00:25 INFO - 'SYSTEMROOT': 'C:\\windows',
17:00:25 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
17:00:25 INFO - 'TEST1': 'testie',
17:00:25 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
17:00:25 INFO - 'USERDOMAIN': 'T-W732-IX-066',
17:00:25 INFO - 'USERNAME': 'cltbld',
17:00:25 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
17:00:25 INFO - 'WINDIR': 'C:\\windows',
17:00:25 INFO - 'WINDOWS_TRACING_FLAGS': '3',
17:00:25 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
17:00:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
17:00:25 INFO - Ignoring indexes: https://pypi.python.org/simple/
17:00:25 INFO - Downloading/unpacking mozsystemmonitor==0.3
17:00:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
17:00:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
17:00:26 INFO - Downloading mozsystemmonitor-0.3.tar.gz
17:00:26 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor
17:00:26 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)
17:00:26 INFO - Installing collected packages: mozsystemmonitor
17:00:26 INFO - Running setup.py install for mozsystemmonitor
17:00:26 INFO - Successfully installed mozsystemmonitor
17:00:26 INFO - Cleaning up...
17:00:26 INFO - Return code: 0
17:00:26 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv
17:00:26 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')]}
17:00:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
17:00:26 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')]}
17:00:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
17:00:26 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017E1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01885E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0178B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01892E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012AA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01954520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-066', 'USERDOMAIN': 'T-W732-IX-066', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-066', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
17:00:27 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
17:00:27 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
17:00:27 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
17:00:27 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
17:00:27 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
17:00:27 INFO - 'COMPUTERNAME': 'T-W732-IX-066',
17:00:27 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
17:00:27 INFO - 'CYGWINBASE': 'C:\\cygwin',
17:00:27 INFO - 'DCLOCATION': 'SCL3',
17:00:27 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
17:00:27 INFO - 'FP_NO_HOST_CHECK': 'NO',
17:00:27 INFO - 'HOMEDRIVE': 'C:',
17:00:27 INFO - 'HOMEPATH': '\\Users\\cltbld',
17:00:27 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
17:00:27 INFO - 'KTS_VERSION': '1.19c',
17:00:27 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
17:00:27 INFO - 'LOGONSERVER': '\\\\T-W732-IX-066',
17:00:27 INFO - 'MONDIR': 'C:\\Monitor_config\\',
17:00:27 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
17:00:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
17:00:27 INFO - 'MOZ_NO_REMOTE': '1',
17:00:27 INFO - 'NO_EM_RESTART': '1',
17:00:27 INFO - 'NUMBER_OF_PROCESSORS': '8',
17:00:27 INFO - 'OS': 'Windows_NT',
17:00:27 INFO - 'OURDRIVE': 'C:',
17:00:27 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
17:00:27 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
17:00:27 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
17:00:27 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
17:00:27 INFO - 'PROCESSOR_LEVEL': '6',
17:00:27 INFO - 'PROCESSOR_REVISION': '1e05',
17:00:27 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
17:00:27 INFO - 'PROGRAMFILES': 'C:\\Program Files',
17:00:27 INFO - 'PROMPT': '$P$G',
17:00:27 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
17:00:27 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
17:00:27 INFO - 'PUBLIC': 'C:\\Users\\Public',
17:00:27 INFO - 'PWD': 'C:\\slave\\test',
17:00:27 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
17:00:27 INFO - 'SLAVEDIR': 'C:\\slave\\',
17:00:27 INFO - 'SYSTEMDRIVE': 'C:',
17:00:27 INFO - 'SYSTEMROOT': 'C:\\windows',
17:00:27 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
17:00:27 INFO - 'TEST1': 'testie',
17:00:27 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
17:00:27 INFO - 'USERDOMAIN': 'T-W732-IX-066',
17:00:27 INFO - 'USERNAME': 'cltbld',
17:00:27 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
17:00:27 INFO - 'WINDIR': 'C:\\windows',
17:00:27 INFO - 'WINDOWS_TRACING_FLAGS': '3',
17:00:27 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
17:00:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
17:00:27 INFO - Ignoring indexes: https://pypi.python.org/simple/
17:00:27 INFO - Downloading/unpacking jsonschema==2.5.1
17:00:27 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
17:00:27 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
17:00:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl
17:00:28 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1)
17:00:28 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
17:00:28 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
17:00:28 INFO - Downloading functools32-3.2.3-2.tar.gz
17:00:28 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32
17:00:28 INFO - warning: no files found matching '*.txt'
17:00:28 INFO - no previously-included directories found matching 'build'
17:00:28 INFO - no previously-included directories found matching 'dist'
17:00:28 INFO - no previously-included directories found matching '.git*'
17:00:28 INFO - Installing collected packages: jsonschema, functools32
17:00:28 INFO - Running setup.py install for functools32
17:00:28 INFO - warning: no files found matching '*.txt'
17:00:28 INFO - no previously-included directories found matching 'build'
17:00:28 INFO - no previously-included directories found matching 'dist'
17:00:28 INFO - no previously-included directories found matching '.git*'
17:00:28 INFO - Successfully installed jsonschema functools32
17:00:28 INFO - Cleaning up...
17:00:29 INFO - Return code: 0
17:00:29 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv
17:00:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
17:00:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
17:00:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
17:00:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
17:00:29 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017E1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01885E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0178B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01892E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012AA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01954520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-066', 'USERDOMAIN': 'T-W732-IX-066', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-066', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
17:00:29 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in C:\slave\test\build
17:00:29 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2
17:00:29 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
17:00:29 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
17:00:29 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
17:00:29 INFO - 'COMPUTERNAME': 'T-W732-IX-066',
17:00:29 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
17:00:29 INFO - 'CYGWINBASE': 'C:\\cygwin',
17:00:29 INFO - 'DCLOCATION': 'SCL3',
17:00:29 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
17:00:29 INFO - 'FP_NO_HOST_CHECK': 'NO',
17:00:29 INFO - 'HOMEDRIVE': 'C:',
17:00:29 INFO - 'HOMEPATH': '\\Users\\cltbld',
17:00:29 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
17:00:29 INFO - 'KTS_VERSION': '1.19c',
17:00:29 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
17:00:29 INFO - 'LOGONSERVER': '\\\\T-W732-IX-066',
17:00:29 INFO - 'MONDIR': 'C:\\Monitor_config\\',
17:00:29 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
17:00:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
17:00:29 INFO - 'MOZ_NO_REMOTE': '1',
17:00:29 INFO - 'NO_EM_RESTART': '1',
17:00:29 INFO - 'NUMBER_OF_PROCESSORS': '8',
17:00:29 INFO - 'OS': 'Windows_NT',
17:00:29 INFO - 'OURDRIVE': 'C:',
17:00:29 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
17:00:29 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
17:00:29 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
17:00:29 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
17:00:29 INFO - 'PROCESSOR_LEVEL': '6',
17:00:29 INFO - 'PROCESSOR_REVISION': '1e05',
17:00:29 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
17:00:29 INFO - 'PROGRAMFILES': 'C:\\Program Files',
17:00:29 INFO - 'PROMPT': '$P$G',
17:00:29 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
17:00:29 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
17:00:29 INFO - 'PUBLIC': 'C:\\Users\\Public',
17:00:29 INFO - 'PWD': 'C:\\slave\\test',
17:00:29 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
17:00:29 INFO - 'SLAVEDIR': 'C:\\slave\\',
17:00:29 INFO - 'SYSTEMDRIVE': 'C:',
17:00:29 INFO - 'SYSTEMROOT': 'C:\\windows',
17:00:29 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
17:00:29 INFO - 'TEST1': 'testie',
17:00:29 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
17:00:29 INFO - 'USERDOMAIN': 'T-W732-IX-066',
17:00:29 INFO - 'USERNAME': 'cltbld',
17:00:29 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
17:00:29 INFO - 'WINDIR': 'C:\\windows',
17:00:29 INFO - 'WINDOWS_TRACING_FLAGS': '3',
17:00:29 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
17:00:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
17:00:29 INFO - Ignoring indexes: https://pypi.python.org/simple/
17:00:29 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages
17:00:29 INFO - Cleaning up...
17:00:29 INFO - Return code: 0
17:00:29 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv
17:00:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
17:00:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
17:00:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
17:00:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
17:00:29 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017E1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01885E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0178B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01892E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012AA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01954520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-066', 'USERDOMAIN': 'T-W732-IX-066', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-066', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
17:00:29 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in C:\slave\test\build
17:00:29 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4
17:00:29 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
17:00:29 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
17:00:29 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
17:00:29 INFO - 'COMPUTERNAME': 'T-W732-IX-066',
17:00:29 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
17:00:29 INFO - 'CYGWINBASE': 'C:\\cygwin',
17:00:29 INFO - 'DCLOCATION': 'SCL3',
17:00:29 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
17:00:29 INFO - 'FP_NO_HOST_CHECK': 'NO',
17:00:29 INFO - 'HOMEDRIVE': 'C:',
17:00:29 INFO - 'HOMEPATH': '\\Users\\cltbld',
17:00:29 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
17:00:29 INFO - 'KTS_VERSION': '1.19c',
17:00:29 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
17:00:29 INFO - 'LOGONSERVER': '\\\\T-W732-IX-066',
17:00:29 INFO - 'MONDIR': 'C:\\Monitor_config\\',
17:00:29 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
17:00:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
17:00:29 INFO - 'MOZ_NO_REMOTE': '1',
17:00:29 INFO - 'NO_EM_RESTART': '1',
17:00:29 INFO - 'NUMBER_OF_PROCESSORS': '8',
17:00:29 INFO - 'OS': 'Windows_NT',
17:00:29 INFO - 'OURDRIVE': 'C:',
17:00:29 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
17:00:29 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
17:00:29 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
17:00:29 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
17:00:29 INFO - 'PROCESSOR_LEVEL': '6',
17:00:29 INFO - 'PROCESSOR_REVISION': '1e05',
17:00:29 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
17:00:29 INFO - 'PROGRAMFILES': 'C:\\Program Files',
17:00:29 INFO - 'PROMPT': '$P$G',
17:00:29 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
17:00:29 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
17:00:29 INFO - 'PUBLIC': 'C:\\Users\\Public',
17:00:29 INFO - 'PWD': 'C:\\slave\\test',
17:00:29 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
17:00:29 INFO - 'SLAVEDIR': 'C:\\slave\\',
17:00:29 INFO - 'SYSTEMDRIVE': 'C:',
17:00:29 INFO - 'SYSTEMROOT': 'C:\\windows',
17:00:29 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
17:00:29 INFO - 'TEST1': 'testie',
17:00:29 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
17:00:29 INFO - 'USERDOMAIN': 'T-W732-IX-066',
17:00:29 INFO - 'USERNAME': 'cltbld',
17:00:29 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
17:00:29 INFO - 'WINDIR': 'C:\\windows',
17:00:29 INFO - 'WINDOWS_TRACING_FLAGS': '3',
17:00:29 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
17:00:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
17:00:30 INFO - Ignoring indexes: https://pypi.python.org/simple/
17:00:30 INFO - Downloading/unpacking blobuploader==1.2.4
17:00:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
17:00:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
17:00:31 INFO - Downloading blobuploader-1.2.4.tar.gz
17:00:31 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader
17:00:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4)
17:00:31 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
17:00:31 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
17:00:31 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests
17:00:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4)
17:00:31 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
17:00:31 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
17:00:31 INFO - Downloading docopt-0.6.1.tar.gz
17:00:31 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt
17:00:32 INFO - Installing collected packages: blobuploader, requests, docopt
17:00:32 INFO - Running setup.py install for blobuploader
17:00:32 INFO - Running setup.py install for requests
17:00:32 INFO - Running setup.py install for docopt
17:00:33 INFO - Successfully installed blobuploader requests docopt
17:00:33 INFO - Cleaning up...
17:00:34 INFO - Return code: 0
17:00:34 INFO - Installing None into virtualenv C:\slave\test\build/venv
17:00:34 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')]}
17:00:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
17:00:34 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')]}
17:00:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
17:00:34 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--no-deps', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017E1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01885E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0178B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01892E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012AA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01954520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-066', 'USERDOMAIN': 'T-W732-IX-066', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-066', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
17:00:34 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
17:00:34 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
17:00:34 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
17:00:34 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
17:00:34 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
17:00:34 INFO - 'COMPUTERNAME': 'T-W732-IX-066',
17:00:34 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
17:00:34 INFO - 'CYGWINBASE': 'C:\\cygwin',
17:00:34 INFO - 'DCLOCATION': 'SCL3',
17:00:34 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
17:00:34 INFO - 'FP_NO_HOST_CHECK': 'NO',
17:00:34 INFO - 'HOMEDRIVE': 'C:',
17:00:34 INFO - 'HOMEPATH': '\\Users\\cltbld',
17:00:34 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
17:00:34 INFO - 'KTS_VERSION': '1.19c',
17:00:34 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
17:00:34 INFO - 'LOGONSERVER': '\\\\T-W732-IX-066',
17:00:34 INFO - 'MONDIR': 'C:\\Monitor_config\\',
17:00:34 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
17:00:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
17:00:34 INFO - 'MOZ_NO_REMOTE': '1',
17:00:34 INFO - 'NO_EM_RESTART': '1',
17:00:34 INFO - 'NUMBER_OF_PROCESSORS': '8',
17:00:34 INFO - 'OS': 'Windows_NT',
17:00:34 INFO - 'OURDRIVE': 'C:',
17:00:34 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;',
17:00:34 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
17:00:34 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
17:00:34 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
17:00:34 INFO - 'PROCESSOR_LEVEL': '6',
17:00:34 INFO - 'PROCESSOR_REVISION': '1e05',
17:00:34 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
17:00:34 INFO - 'PROGRAMFILES': 'C:\\Program Files',
17:00:34 INFO - 'PROMPT': '$P$G',
17:00:34 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
17:00:34 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
17:00:34 INFO - 'PUBLIC': 'C:\\Users\\Public',
17:00:34 INFO - 'PWD': 'C:\\slave\\test',
17:00:34 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
17:00:34 INFO - 'SLAVEDIR': 'C:\\slave\\',
17:00:34 INFO - 'SYSTEMDRIVE': 'C:',
17:00:34 INFO - 'SYSTEMROOT': 'C:\\windows',
17:00:34 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
17:00:34 INFO - 'TEST1': 'testie',
17:00:34 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
17:00:34 INFO - 'USERDOMAIN': 'T-W732-IX-066',
17:00:34 INFO - 'USERNAME': 'cltbld',
17:00:34 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
17:00:34 INFO - 'WINDIR': 'C:\\windows',
17:00:34 INFO - 'WINDOWS_TRACING_FLAGS': '3',
17:00:34 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
17:00:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
17:00:34 INFO - Ignoring indexes: https://pypi.python.org/simple/
17:00:34 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
17:00:34 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-esspdm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
17:00:35 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
17:00:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zp3kkq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
17:00:35 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
17:00:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-olkt5a-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
17:00:35 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
17:00:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lh5hzm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
17:00:35 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
17:00:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-six874-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
17:00:35 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
17:00:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zwffhu-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
17:00:35 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
17:00:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-18fbjq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
17:00:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
17:00:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-8u4mut-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
17:00:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
17:00:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dzj6bp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
17:00:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
17:00:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-i4hpto-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
17:00:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
17:00:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ylye8q-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
17:00:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
17:00:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-3p6y07-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
17:00:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
17:00:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-h_ovnu-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
17:00:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
17:00:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xkx3jm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
17:00:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
17:00:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-e5kclf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
17:00:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
17:00:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-igvab9-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
17:00:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
17:00:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-adp85f-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
17:00:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
17:00:37 INFO - Running setup.py install for manifestparser
17:00:37 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts
17:00:37 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts
17:00:37 INFO - Running setup.py install for mozcrash
17:00:38 INFO - Running setup.py install for mozdebug
17:00:38 INFO - Running setup.py install for mozdevice
17:00:38 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts
17:00:38 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts
17:00:38 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts
17:00:38 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts
17:00:39 INFO - Running setup.py install for mozfile
17:00:39 INFO - Running setup.py install for mozhttpd
17:00:39 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts
17:00:39 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts
17:00:39 INFO - Running setup.py install for mozinfo
17:00:40 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts
17:00:40 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts
17:00:40 INFO - Running setup.py install for mozInstall
17:00:40 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts
17:00:40 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts
17:00:40 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts
17:00:40 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts
17:00:40 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts
17:00:40 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts
17:00:40 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts
17:00:40 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts
17:00:40 INFO - Running setup.py install for mozleak
17:00:40 INFO - Running setup.py install for mozlog
17:00:41 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts
17:00:41 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts
17:00:41 INFO - Running setup.py install for moznetwork
17:00:42 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts
17:00:42 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts
17:00:42 INFO - Running setup.py install for mozprocess
17:00:42 INFO - Running setup.py install for mozprofile
17:00:42 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts
17:00:42 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts
17:00:42 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts
17:00:42 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts
17:00:42 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts
17:00:43 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts
17:00:43 INFO - Running setup.py install for mozrunner
17:00:43 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts
17:00:43 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts
17:00:44 INFO - Running setup.py install for mozscreenshot
17:00:44 INFO - Running setup.py install for moztest
17:00:44 INFO - Running setup.py install for mozversion
17:00:45 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts
17:00:45 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts
17:00:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion
17:00:45 INFO - Cleaning up...
17:00:45 INFO - Return code: 0
17:00:45 INFO - Installing None into virtualenv C:\slave\test\build/venv
17:00: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')]}
17:00:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
17:00: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')]}
17:00:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
17:00:45 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017E1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01885E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0178B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01892E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012AA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01954520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-066', 'USERDOMAIN': 'T-W732-IX-066', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-066', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
17:00:45 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
17:00:45 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
17:00:45 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
17:00:45 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
17:00:45 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
17:00:45 INFO - 'COMPUTERNAME': 'T-W732-IX-066',
17:00:45 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
17:00:45 INFO - 'CYGWINBASE': 'C:\\cygwin',
17:00:45 INFO - 'DCLOCATION': 'SCL3',
17:00:45 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
17:00:45 INFO - 'FP_NO_HOST_CHECK': 'NO',
17:00:45 INFO - 'HOMEDRIVE': 'C:',
17:00:45 INFO - 'HOMEPATH': '\\Users\\cltbld',
17:00:45 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
17:00:45 INFO - 'KTS_VERSION': '1.19c',
17:00:45 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
17:00:45 INFO - 'LOGONSERVER': '\\\\T-W732-IX-066',
17:00:45 INFO - 'MONDIR': 'C:\\Monitor_config\\',
17:00:45 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
17:00:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
17:00:45 INFO - 'MOZ_NO_REMOTE': '1',
17:00:45 INFO - 'NO_EM_RESTART': '1',
17:00:45 INFO - 'NUMBER_OF_PROCESSORS': '8',
17:00:45 INFO - 'OS': 'Windows_NT',
17:00:45 INFO - 'OURDRIVE': 'C:',
17:00: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;',
17:00:45 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
17:00:45 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
17:00:45 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
17:00:45 INFO - 'PROCESSOR_LEVEL': '6',
17:00:45 INFO - 'PROCESSOR_REVISION': '1e05',
17:00:45 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
17:00:45 INFO - 'PROGRAMFILES': 'C:\\Program Files',
17:00:45 INFO - 'PROMPT': '$P$G',
17:00:45 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
17:00:45 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
17:00:45 INFO - 'PUBLIC': 'C:\\Users\\Public',
17:00:45 INFO - 'PWD': 'C:\\slave\\test',
17:00:45 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
17:00:45 INFO - 'SLAVEDIR': 'C:\\slave\\',
17:00:45 INFO - 'SYSTEMDRIVE': 'C:',
17:00:45 INFO - 'SYSTEMROOT': 'C:\\windows',
17:00:45 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
17:00:45 INFO - 'TEST1': 'testie',
17:00:45 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
17:00:45 INFO - 'USERDOMAIN': 'T-W732-IX-066',
17:00:45 INFO - 'USERNAME': 'cltbld',
17:00:45 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
17:00:45 INFO - 'WINDIR': 'C:\\windows',
17:00:45 INFO - 'WINDOWS_TRACING_FLAGS': '3',
17:00:45 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
17:00:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
17:00:45 INFO - Ignoring indexes: https://pypi.python.org/simple/
17:00:45 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
17:00:45 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bqie6m-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
17:00:46 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))
17:00:46 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
17:00:46 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-nf10pr-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
17:00:46 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))
17:00:46 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
17:00:46 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pvqc9o-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
17:00:46 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))
17:00:46 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
17:00:46 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gswqrq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
17:00:46 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))
17:00:46 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
17:00:46 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-e19fpk-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
17:00:46 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))
17:00:46 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
17:00:46 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lxvoq9-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
17:00:46 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))
17:00:46 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
17:00:46 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rwisph-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
17:00:46 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))
17:00:46 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
17:00:46 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-oevbqf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
17:00:46 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))
17:00:46 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
17:00:46 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-tnc0dg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
17:00:47 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))
17:00:47 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
17:00:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-eigizi-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
17:00:47 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))
17:00:47 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
17:00:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ebsxcf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
17:00:47 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))
17:00:47 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
17:00:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vmqw3n-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
17:00:47 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))
17:00:47 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
17:00:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gr04ch-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
17:00:47 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))
17:00:47 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
17:00:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-a1cme6-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
17:00:47 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))
17:00:47 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
17:00:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-yid2gs-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
17:00:48 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))
17:00:48 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
17:00:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vetdsz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
17:00:48 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))
17:00:48 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
17:00:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vtkfp0-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
17:00:48 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))
17:00:48 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))
17:00:48 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))
17:00:48 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))
17:00:48 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))
17:00:48 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))
17:00:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10))
17:00:48 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
17:00:48 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
17:00:49 INFO - Downloading blessings-1.6.tar.gz
17:00:49 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings
17:00:49 INFO - Installing collected packages: blessings
17:00:49 INFO - Running setup.py install for blessings
17:00:50 INFO - Successfully installed blessings
17:00:50 INFO - Cleaning up...
17:00:52 INFO - Return code: 0
17:00:52 INFO - Done creating virtualenv C:\slave\test\build/venv.
17:00:52 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
17:00:52 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
17:00:53 INFO - Reading from file tmpfile_stdout
17:00:53 INFO - Using _rmtree_windows ...
17:00:53 INFO - Using _rmtree_windows ...
17:00:53 INFO - Current package versions:
17:00:53 INFO - blessings == 1.6
17:00:53 INFO - blobuploader == 1.2.4
17:00:53 INFO - docopt == 0.6.1
17:00:53 INFO - functools32 == 3.2.3-2
17:00:53 INFO - jsonschema == 2.5.1
17:00:53 INFO - manifestparser == 1.1
17:00:53 INFO - mozInstall == 1.12
17:00:53 INFO - mozcrash == 0.17
17:00:53 INFO - mozdebug == 0.1
17:00:53 INFO - mozdevice == 0.48
17:00:53 INFO - mozfile == 1.2
17:00:53 INFO - mozhttpd == 0.7
17:00:53 INFO - mozinfo == 0.9
17:00:53 INFO - mozleak == 0.1
17:00:53 INFO - mozlog == 3.4
17:00:53 INFO - moznetwork == 0.27
17:00:53 INFO - mozprocess == 0.23
17:00:53 INFO - mozprofile == 0.28
17:00:53 INFO - mozrunner == 6.12
17:00:53 INFO - mozscreenshot == 0.1
17:00:53 INFO - mozsystemmonitor == 0.3
17:00:53 INFO - moztest == 0.7
17:00:53 INFO - mozversion == 1.4
17:00:53 INFO - psutil == 3.1.1
17:00:53 INFO - requests == 1.2.3
17:00:53 INFO - Installing None into virtualenv C:\slave\test\build/venv
17:00:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
17:00:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
17:00:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
17:00:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
17:00:53 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\talos\\requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017E1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01885E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0178B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01892E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012AA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01954520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-066', 'USERDOMAIN': 'T-W732-IX-066', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-066', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
17:00:53 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
17:00:53 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
17:00:53 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
17:00:53 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
17:00:53 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
17:00:53 INFO - 'COMPUTERNAME': 'T-W732-IX-066',
17:00:53 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
17:00:53 INFO - 'CYGWINBASE': 'C:\\cygwin',
17:00:53 INFO - 'DCLOCATION': 'SCL3',
17:00:53 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
17:00:53 INFO - 'FP_NO_HOST_CHECK': 'NO',
17:00:53 INFO - 'HOMEDRIVE': 'C:',
17:00:53 INFO - 'HOMEPATH': '\\Users\\cltbld',
17:00:53 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
17:00:53 INFO - 'KTS_VERSION': '1.19c',
17:00:53 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
17:00:53 INFO - 'LOGONSERVER': '\\\\T-W732-IX-066',
17:00:53 INFO - 'MONDIR': 'C:\\Monitor_config\\',
17:00:53 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
17:00:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
17:00:53 INFO - 'MOZ_NO_REMOTE': '1',
17:00:53 INFO - 'NO_EM_RESTART': '1',
17:00:53 INFO - 'NUMBER_OF_PROCESSORS': '8',
17:00:53 INFO - 'OS': 'Windows_NT',
17:00:53 INFO - 'OURDRIVE': 'C:',
17:00:53 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
17:00:53 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
17:00:53 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
17:00:53 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
17:00:53 INFO - 'PROCESSOR_LEVEL': '6',
17:00:53 INFO - 'PROCESSOR_REVISION': '1e05',
17:00:53 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
17:00:53 INFO - 'PROGRAMFILES': 'C:\\Program Files',
17:00:53 INFO - 'PROMPT': '$P$G',
17:00:53 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
17:00:53 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
17:00:53 INFO - 'PUBLIC': 'C:\\Users\\Public',
17:00:53 INFO - 'PWD': 'C:\\slave\\test',
17:00:53 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
17:00:53 INFO - 'SLAVEDIR': 'C:\\slave\\',
17:00:53 INFO - 'SYSTEMDRIVE': 'C:',
17:00:53 INFO - 'SYSTEMROOT': 'C:\\windows',
17:00:53 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
17:00:53 INFO - 'TEST1': 'testie',
17:00:53 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
17:00:53 INFO - 'USERDOMAIN': 'T-W732-IX-066',
17:00:53 INFO - 'USERNAME': 'cltbld',
17:00:53 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
17:00:53 INFO - 'WINDIR': 'C:\\windows',
17:00:53 INFO - 'WINDOWS_TRACING_FLAGS': '3',
17:00:53 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
17:00:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
17:00:53 INFO - Ignoring indexes: https://pypi.python.org/simple/
17:00:53 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))
17:00:53 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))
17:00:53 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))
17:00:53 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))
17:00:53 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))
17:00:53 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))
17:00:53 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))
17:00:53 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))
17:00:53 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))
17:00:53 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))
17:00:53 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))
17:00:53 INFO - Cleaning up...
17:00:53 INFO - Return code: 0
17:00:53 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv
17:00:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
17:00:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
17:00:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
17:00:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
17:00:53 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017E1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01885E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0178B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01892E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012AA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01954520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-066', 'USERDOMAIN': 'T-W732-IX-066', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-066', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
17:00:53 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
17:00:53 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
17:00:53 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
17:00:53 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
17:00:53 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
17:00:53 INFO - 'COMPUTERNAME': 'T-W732-IX-066',
17:00:53 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
17:00:53 INFO - 'CYGWINBASE': 'C:\\cygwin',
17:00:53 INFO - 'DCLOCATION': 'SCL3',
17:00:53 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
17:00:53 INFO - 'FP_NO_HOST_CHECK': 'NO',
17:00:53 INFO - 'HOMEDRIVE': 'C:',
17:00:53 INFO - 'HOMEPATH': '\\Users\\cltbld',
17:00:53 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
17:00:53 INFO - 'KTS_VERSION': '1.19c',
17:00:53 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
17:00:53 INFO - 'LOGONSERVER': '\\\\T-W732-IX-066',
17:00:53 INFO - 'MONDIR': 'C:\\Monitor_config\\',
17:00:53 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
17:00:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
17:00:53 INFO - 'MOZ_NO_REMOTE': '1',
17:00:53 INFO - 'NO_EM_RESTART': '1',
17:00:53 INFO - 'NUMBER_OF_PROCESSORS': '8',
17:00:53 INFO - 'OS': 'Windows_NT',
17:00:53 INFO - 'OURDRIVE': 'C:',
17:00:53 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
17:00:53 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
17:00:53 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
17:00:53 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
17:00:53 INFO - 'PROCESSOR_LEVEL': '6',
17:00:53 INFO - 'PROCESSOR_REVISION': '1e05',
17:00:53 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
17:00:53 INFO - 'PROGRAMFILES': 'C:\\Program Files',
17:00:53 INFO - 'PROMPT': '$P$G',
17:00:53 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
17:00:53 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
17:00:53 INFO - 'PUBLIC': 'C:\\Users\\Public',
17:00:53 INFO - 'PWD': 'C:\\slave\\test',
17:00:53 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
17:00:53 INFO - 'SLAVEDIR': 'C:\\slave\\',
17:00:53 INFO - 'SYSTEMDRIVE': 'C:',
17:00:53 INFO - 'SYSTEMROOT': 'C:\\windows',
17:00:53 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
17:00:53 INFO - 'TEST1': 'testie',
17:00:53 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
17:00:53 INFO - 'USERDOMAIN': 'T-W732-IX-066',
17:00:53 INFO - 'USERNAME': 'cltbld',
17:00:53 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
17:00:53 INFO - 'WINDIR': 'C:\\windows',
17:00:53 INFO - 'WINDOWS_TRACING_FLAGS': '3',
17:00:53 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
17:00:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
17:00:53 INFO - Ignoring indexes: https://pypi.python.org/simple/
17:00:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages
17:00:53 INFO - Cleaning up...
17:00:53 INFO - Return code: 0
17:00:53 INFO - Running post-action listener: _resource_record_post_action
17:00:53 INFO - Running post-action listener: _start_resource_monitoring
17:00:53 INFO - Starting resource monitoring.
17:00:54 INFO - [mozharness: 2016-10-21 00:00:54.070000Z] Finished create-virtualenv step (success)
17:00:54 INFO - [mozharness: 2016-10-21 00:00:54.070000Z] Running install step.
17:00:54 INFO - Running pre-action listener: _resource_record_pre_action
17:00:54 INFO - Running main action method: install
17:00:54 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
17:00:54 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
17:00:54 INFO - Reading from file tmpfile_stdout
17:00:54 INFO - Using _rmtree_windows ...
17:00:54 INFO - Using _rmtree_windows ...
17:00:54 INFO - Detecting whether we're running mozinstall >=1.0...
17:00:54 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h']
17:00:54 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h
17:00:54 INFO - Reading from file tmpfile_stdout
17:00:54 INFO - Output received:
17:00:54 INFO - Usage: mozinstall-script.py [options] installer
17:00:54 INFO - Options:
17:00:54 INFO - -h, --help show this help message and exit
17:00:54 INFO - -d DEST, --destination=DEST
17:00:54 INFO - Directory to install application into. [default:
17:00:54 INFO - "C:\slave\test"]
17:00:54 INFO - --app=APP Application being installed. [default: firefox]
17:00:54 INFO - Using _rmtree_windows ...
17:00:54 INFO - Using _rmtree_windows ...
17:00:54 INFO - mkdir: C:\slave\test\build\application
17:00:54 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']
17:00:54 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
17:00:56 INFO - Reading from file tmpfile_stdout
17:00:56 INFO - Output received:
17:00:56 INFO - C:\slave\test\build\application\firefox\firefox.exe
17:00:56 INFO - Using _rmtree_windows ...
17:00:56 INFO - Using _rmtree_windows ...
17:00:56 INFO - Running post-action listener: _resource_record_post_action
17:00:56 INFO - [mozharness: 2016-10-21 00:00:56.640000Z] Finished install step (success)
17:00:56 INFO - [mozharness: 2016-10-21 00:00:56.640000Z] Running run-tests step.
17:00:56 INFO - Running pre-action listener: _resource_record_pre_action
17:00:56 INFO - Running main action method: run_tests
17:00:56 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version']
17:00:56 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version
17:00:56 INFO - Python 2.7.3
17:00:56 INFO - Return code: 0
17:00:56 INFO - grabbing minidump binary from tooltool
17:00:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
17:00:56 INFO - retry: Calling run_command with args: (['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01892E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012AA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01954520>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1
17:00:56 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
17:00:56 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
17:00:56 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
17:00:56 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'...
17:00:57 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmpsmhkag
17:00:57 INFO - INFO - File integrity verified, renaming tmpsmhkag to win32-minidump_stackwalk.exe
17:00:57 INFO - Return code: 0
17:00:57 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755
17:00:57 INFO - mkdir: C:\slave\test\build\blobber_upload_dir
17:00:57 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir
17:00:57 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe
17:00:57 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir
17:00:57 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476992154/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-066', '--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
17:00:57 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476992154/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-066 --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
17:00:57 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
17:00:57 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
17:00:57 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
17:00:57 INFO - 'COMPUTERNAME': 'T-W732-IX-066',
17:00:57 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
17:00:57 INFO - 'CYGWINBASE': 'C:\\cygwin',
17:00:57 INFO - 'DCLOCATION': 'SCL3',
17:00:57 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
17:00:57 INFO - 'FP_NO_HOST_CHECK': 'NO',
17:00:57 INFO - 'HOMEDRIVE': 'C:',
17:00:57 INFO - 'HOMEPATH': '\\Users\\cltbld',
17:00:57 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
17:00:57 INFO - 'KTS_VERSION': '1.19c',
17:00:57 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
17:00:57 INFO - 'LOGONSERVER': '\\\\T-W732-IX-066',
17:00:57 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir',
17:00:57 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe',
17:00:57 INFO - 'MONDIR': 'C:\\Monitor_config\\',
17:00:57 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
17:00:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
17:00:57 INFO - 'MOZ_NO_REMOTE': '1',
17:00:57 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir',
17:00:57 INFO - 'NO_EM_RESTART': '1',
17:00:57 INFO - 'NUMBER_OF_PROCESSORS': '8',
17:00:57 INFO - 'OS': 'Windows_NT',
17:00:57 INFO - 'OURDRIVE': 'C:',
17:00:57 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;',
17:00:57 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
17:00:57 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
17:00:57 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
17:00:57 INFO - 'PROCESSOR_LEVEL': '6',
17:00:57 INFO - 'PROCESSOR_REVISION': '1e05',
17:00:57 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
17:00:57 INFO - 'PROGRAMFILES': 'C:\\Program Files',
17:00:57 INFO - 'PROMPT': '$P$G',
17:00:57 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
17:00:57 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
17:00:57 INFO - 'PUBLIC': 'C:\\Users\\Public',
17:00:57 INFO - 'PWD': 'C:\\slave\\test',
17:00:57 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos',
17:00:57 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
17:00:57 INFO - 'SLAVEDIR': 'C:\\slave\\',
17:00:57 INFO - 'SYSTEMDRIVE': 'C:',
17:00:57 INFO - 'SYSTEMROOT': 'C:\\windows',
17:00:57 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
17:00:57 INFO - 'TEST1': 'testie',
17:00:57 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
17:00:57 INFO - 'USERDOMAIN': 'T-W732-IX-066',
17:00:57 INFO - 'USERNAME': 'cltbld',
17:00:57 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
17:00:57 INFO - 'WINDIR': 'C:\\windows',
17:00:57 INFO - 'WINDOWS_TRACING_FLAGS': '3',
17:00:57 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
17:00:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
17:00:57 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476992154/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-066', '--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
17:00:58 INFO - mozversion application_buildid: 20161020123554
17:00:58 INFO - mozversion application_changeset: 4be40c5468626cd5065cbc79674c933b908a2450
17:00:58 INFO - mozversion application_display_name: Nightly
17:00:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
17:00:58 INFO - mozversion application_name: Firefox
17:00:58 INFO - mozversion application_remotingname: firefox
17:00:58 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team
17:00:58 INFO - mozversion application_vendor: Mozilla
17:00:58 INFO - mozversion application_version: 52.0a1
17:00:58 INFO - mozversion platform_buildid: 20161020123554
17:00:58 INFO - mozversion platform_changeset: 4be40c5468626cd5065cbc79674c933b908a2450
17:00:58 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team
17:00:58 INFO - mozversion platform_version: 52.0a1
17:00:58 INFO - using testdate: 1477008058
17:00:58 INFO - actual date: 1477008058
17:00:58 INFO - starting webserver on 'localhost:49575'
17:00:58 INFO - SUITE-START | Running 2 tests
17:00:58 INFO - TEST-START | dromaeo_css
17:00:58 INFO - operating with platform_type : w7_
17:00:58 INFO - Initialising browser for dromaeo_css test...
17:00:59 INFO - TEST-INFO | started process 1236 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpir3lit\profile http://localhost:49575/getInfo.html)
17:01:07 INFO - PROCESS | 1236 | __metrics Screen width/height:1600/1200
17:01:07 INFO - PROCESS | 1236 | colorDepth:24
17:01:07 INFO - PROCESS | 1236 | Browser inner width/height: 1010/674
17:01:07 INFO - PROCESS | 1236 | __metrics
17:01:08 INFO - PROCESS | 1236 | 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"
17:01:08 INFO - PROCESS | 1236 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
17:01:09 INFO - TEST-INFO | 1236: exit 0
17:01:09 INFO - Browser initialized.
17:01:09 INFO - Running cycle 1/1 for dromaeo_css test...
17:01:09 INFO - TEST-INFO | started process 4004 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpir3lit\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1)
17:01:10 INFO - PROCESS | 4004 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0
17:01:20 INFO - PROCESS | 4004 | RSS: Main: 122916864
17:01:20 INFO - PROCESS | 4004 |
17:02:11 INFO - PROCESS | 4004 | 1477008131879 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]
17:04:23 INFO - PROCESS | 4004 | Cycle 1(1): loaded http://localhost:49575/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49575/tests/dromaeo/cssquery-ext.html)
17:04:24 INFO - PROCESS | 4004 | RSS: Main: 201785344
17:04:24 INFO - PROCESS | 4004 |
17:07:26 INFO - PROCESS | 4004 | Cycle 1(1): loaded http://localhost:49575/tests/dromaeo/cssquery-ext.html (next: http://localhost:49575/tests/dromaeo/cssquery-jquery.html)
17:07:27 INFO - PROCESS | 4004 | RSS: Main: 198819840
17:07:27 INFO - PROCESS | 4004 |
17:10:29 INFO - PROCESS | 4004 | Cycle 1(1): loaded http://localhost:49575/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49575/tests/dromaeo/cssquery-mootools.html)
17:10:29 INFO - PROCESS | 4004 | RSS: Main: 149110784
17:10:29 INFO - PROCESS | 4004 |
17:13:31 INFO - PROCESS | 4004 | Cycle 1(1): loaded http://localhost:49575/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49575/tests/dromaeo/cssquery-prototype.html)
17:13:32 INFO - PROCESS | 4004 | RSS: Main: 149323776
17:13:32 INFO - PROCESS | 4004 |
17:16:35 INFO - PROCESS | 4004 | Cycle 1(1): loaded http://localhost:49575/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49575/tests/dromaeo/cssquery-yui.html)
17:16:35 INFO - PROCESS | 4004 | RSS: Main: 138645504
17:16:35 INFO - PROCESS | 4004 |
17:19:38 INFO - PROCESS | 4004 | Cycle 1(1): loaded http://localhost:49575/tests/dromaeo/cssquery-yui.html (next: http://localhost:49575/tests/dromaeo/cssquery-dojo.html)
17:19:38 INFO - PROCESS | 4004 | RSS: Main: 185606144
17:19:38 INFO - PROCESS | 4004 |
17:19:38 INFO - PROCESS | 4004 | __start_tp_report
17:19:38 INFO - PROCESS | 4004 | _x_x_mozilla_page_load
17:19:38 INFO - PROCESS | 4004 | _x_x_mozilla_page_load_details
17:19:38 INFO - PROCESS | 4004 | |i|pagename|runs|
17:19:38 INFO - PROCESS | 4004 | |0;dojo.html;2689.152916831198;2706.0664070895546;2715.592661100836;2716.2937636214583;2719.8504082275467;4513.194722111153;4575.88560285993;4607.746573938432;4634.745089020111;4657.0453057123295;4505;4518.412606361177;4596.35651008859;4674;4674.813007479696;4080.795960201993;4083.1425400665894;4090.795460226977;4096.180763847227;4117.629413352797;4088.284550203717;4097.057676734353;4099.0777075158;4105.466289382376;4107.79461026949;3988.1425493518886;4060.1067765091634;4109.4246805447265;4110.506739191302;4120.505539335284;3990.06233535119;4061.167460670585;4074.5138420152784;4100.466939297902;4105.117399759066;3979.1047014421815;3983.521977362706;4021.3565829467143;4040.5959404059654;4125.731827431207;4524.049949510607;4540.114677637862;4542.863714088583;4560.908781824345;4593.63250939924;4733.24268117103;4765.618750499986;4843.733594652293;4859.902801943977;4876.853694389174;4728.881777955544;4798.232282834764;4799.232122860326;4799.688020278671;4894.41267047953;4394.934075988863;4504.391907092566;4524.389207456984;4555.863324100249;4585.70192937458;4906.58294045003;4915.090708218991;5008.073506401327;5013.1227035268685;5015.799368025238;4671.369365135731;4716.386869706985;4726.7636618169645;4824.56578907893;4833.250846118787;4713.010267843722;4787.593054590329;4792.880177995578;4810.302506136591;4872.902541949212;4654.115718013567;4751.453582838018;4835.008823191238;4847.878803029882;4848.424262114827;4677.298405239241;4761.428628564595;4804.567588917014;4858.538438848374;4862.975685121552;3187.952180717291;3243.3026899216784;3267.477203647405;3283.441814891474;3285.704286614216;3044.2693753499;3046.573479712842;3048.908532744021;3049.08527441771;3051.633803943497;4472.329150627432;4483;4493.191225579361;4566.315052742115;4567.817287308537;4753.976230118897;4781.258904869681;4789.209780386267;4810.013947140827;4819.49395313494;4445.688801783909;4482.28283474649;4495.752733599684;4517.774111294422;4530.637548996138;4028.992751812047;4047.3119569673227;4064.30906745848;4080.4287399764057;4088.713790034669;4894.412670479565;4988.8752781180765;5033.546980771748;5047.192449208109;5064.088464076502;4857.878553036061;4865.537773911473;4867.756612169306;4867.80528778859;4885.022995400863;4844.370231869834;4930.802767889244;5013.348264725562;5026.0199261143725;5041.092603331435;4691.530846915418;4717.952820471752;4769.356136921471;4784.545468180518;4827.975860120677;4734.810607575659;4761.880952976203;4835.975820120877;4857.635677324144;4944.554990050912;4636.6058884995155;4658.743769092733;4664.883377915579;4670.276107203253;4796.904061918673;4595.678302518925;4748.501407352178;4755.928661070017;4762.404699412574;4798.640101992434;4568.954310456853;4641.257398816173;4657.301404789308;4731.645126615449;4732.52674732524;4530.977345113253;4613.723176609415;4689.648276379354;4812.783424745965;4821.059893320909;4531.003179300548;4633.675642704979;4642.791074401727;4649.256119020941;4665.2069148245555
17:19:38 INFO - PROCESS | 4004 | |1;ext.html;551.7737727531712;566.5892228134603;570.7688386203587;584.374719050616;597.504071620555;27238.182854514373;38945;39923.60076399235;39933.600663993355;40508.59491405085;39129.60870391295;40067.19865602686;40452.59547404525;40622.7968860156;40663.39004914929;6727.764528241512;7866.803329916741;7910.644021019068;8186.713465028725;8201.097879233279;6890.552114112592;9906;9974.551145198448;10154.3399679021;10243.87317395086;9934.751631209205;10160.695179144637;10168.084872361485;10172.982701729805;10190.184785217201;6891.37977582015;7046.1192350956135;7053.788386348418;10038.447885366302;10117.898821011806;9697.369670971364;10019.148372388356;10038.799224015553;10217.948910255476;10242.6415075472;34427;37818;37840.43239351412;37844.62155378438;37886;3004.9248768780953;3071.5546245794235;3115.9376812463624;3592.7844329340323;3660.871869484555;29487.41025179506;29504.852475737698;29818.70181298181;30089.548656770166;30290.54564181539;3467.514547963287;3500.7549471537004;3507;3532.4701294805727;3546.840392182358;289585;294841;300650;302362.9999999978;302953;51237.74381128071;51522.48477515177;51630.22554661683;51649.74175129175;51696.22455663168;29105.70894291072;29989.700102999126;53773.46226537763;54077.1888421674;54278;11781.234219775688;11833.290002599872;11876.346800926032;11888.346140962158;11889.405529723479;8042.597870106589;8159.755207343789;8514.957425212835;8742.038375778659;8814.250788683034;16708.49874503767;16906.74639880403;17033.148342582823;17047.573810654583;17049.659006819795;6770.221424536143;6801.829954251183;6806.489513286523;6835.316468353125;6926.480513961473;767.7735068154908;799.8440304140753;820.1183727492969;842.5787106446777;875.2297977779514;26344.73655263461;27456.725432745818;27708.722912770616;27816.86091569529;27980.160595182177;900.2482926756232;908.4276905549593;919.691903212418;964.6334392930781;982.2682101834037;13667.726645467035;14352.56942291733;14559.3448294827;14640.633984150269;14674.339654715563;478837;481342;482341;483449;486559;48728;49194;49197;49202.26196607054;49442;984.542187882638;1046.4715318763976;1113.1873732175557;1146.4153281826161;1148.8851114888444;27002.324941875824;27706.861465692542;28128.57807132854;28182.43635127245;28424.005159820135;42076;42448;42497.78751106225;42635.57364426316;42637;360527;374328;382502.43743906624;384204;545187;35437.64562354344;37445.81277093597;38079;38082.61917380791;38115.237695245385;39899.40150897797;42178.36732449077;42502.574974251096;42749.57250427456;43167.13665726605;35164;36231.818840905624;36240.09399765027;36718.632813671524;36919;36534.72128475491;36641;36985.26029479449;37371;38120.80939595284;32610.184745381554;33163.17092072718;34083.659163409044;34352;34774.47838282377;32346;32668.183295416853;33429.83270683295;33572.832135840144;34233.82883085569;24572.139975100785;24625.87687061553;24877.253682388833;24916.75083249144;25381.746182538678
17:19:38 INFO - PROCESS | 4004 | |2;jquery.html;274.4634240060682;296.08214534941675;297.4556561492469;300.2254184204752;309.1729623257785;13112.73774524509;17203.3118675253;17429.21568529416;17581.736273955885;17875;16693.165341732925;16793.496195114134;16872.66254674909;17132.486025419257;17374;5128.846134615958;5290.68255904647;5293.550048245885;5300.973495132538;5313.574914006889;5180.093483640367;5197.428282888879;5332.040232758102;5341.679499230058;5347.893042139155;5291.497307755776;5306.204069389584;5307.363116426034;5309;5320.76056577455;4747.098051370229;5156.020356132323;5194.6104042196985;5296.496832800878;5298.072837253465;4913.115639184945;4951.232558953368;5054.418741844698;5055;5067.037262920034;10164.542595583216;10303.433311167882;10323.122534584569;10457.111145552637;10533.420661863594;1727.2659119874004;1789.597340598368;1795.2011354947053;1802.855771538287;1983.573531690679;8165.550894700789;8201.630926608317;8347.373946953941;8494.447860889022;8553.48679079251;1636.7872176616963;1650.719377705781;1682.4363838114245;1683.7642730017808;1740.4082611911883;82792;82848.88662247147;84050;85765;86253.56873215656;8554.144585541397;8770.780730481789;8885.600147993357;8904.643814247487;8954.238889694449;10265.384076955257;10390.168786497063;10917.781644367069;11168.888311116785;11326.093912487146;6258.186435763321;6261.24865016201;6298.086777417248;6369.331220221903;6448.645324507103;5970.820875373745;6042.758289668452;6091.208142941389;6115.938840611626;6189.721462534197;3652.634736526379;3674.356987527172;3681.153334732973;3719.590845007047;3744.5132132823105;1460.8247010358612;1468.8017117688976;1470.9264536773119;1576.7713681516345;1588.4440445843861;790.0795573157322;790.6165509727778;837.4849467577518;866.6273502393905;897.1521911793424;17652;17666.558336041446;17676.381326653507;17856.535866033493;18244.726329105073;802.1537278171585;806;808.5068108453838;836.8117173635883;863.0334025891042;9370.344075914756;9867.605295788231;9901.554430050664;9992.600295988079;10076.294659373774;399026;415583;415649;416607;421439;92332;95915;96306;97514;98898;902.7607683963755;909.9181073703398;922.5018490015451;922.5617831530104;926.4580220570907;15581.143037133066;15618.45335413255;15673;15797.842021579638;16148.273327700517;33886.83056584701;42335.576644233166;43135.784321078194;43516.78241608771;44727.329090064326;57352.71323643522;58481;58788.70605646945;59961.400385995585;60241.69879150576;6733.865322693421;6957.721691132298;7107.893381599383;7118.857622847618;7393.8151546211775;12225.572104976283;12662.240265584094;12960.157589756636;13028.47886084546;13136.27750473733;10588.205884558534;10683.198760092868;10786.514606842868;10952.452377381258;10988.285761425483;10195.082442580204;10398.948005259925;10545.52545135486;10870.61952831647;10892.564297428014;10863.903787420548;10903.454827258447;10988.835167472334;10996.615118470816;11185.552577896793;6969.372756451944;6996.930030699627;6998.300169983165;7145.21402645719;7505.512141710771;7136.946730551093;10321.896781032094;10764.246502744732;11006.834897476705;11137.220394572301
17:19:38 INFO - PROCESS | 4004 | |3;mootools.html;1227.7298994221273;1246.7818131826928;1288.1820044271888;1552.223888055972;1554.300564745864;6122.663253521066;6382.904256436157;6562;6977.895331570025;6980.3019698030175;6301.2753533343675;6377.553571250014;6591.242007169177;6790.04939308496;6817.147856517935;1824.3523549140064;1853.898035608041;1854.8701590888643;1855.5639424735177;1859.7675290588677;1844.5112045308003;1844.6679597672414;1859.1355019915804;1864.925402983879;1886.8396186324178;1621.059785324515;1675.8156602773686;1810.9185086671066;1857.229249861306;1861.0322632231232;1796.0570700382273;1820.6722789897813;1827.0773259503999;1827.5431142214447;1829.1037391678049;1830.5515148788631;1831.2674930028095;1847.094923487495;1848.6117915237817;1856.3224423085612;6432.742690292383;6532;6534.346565343475;6543.083968244449;6562.376574225441;3907.5701672815967;3982.283189025974;4000.659943904772;4008.959910400888;4013.25754735372;2572.6141078838136;2643.6959749628845;2674.211107723226;2674.9331266718486;2680.5041067402594;826.5454000299811;849.9745007649781;859.9871001934976;860.1011942520081;863.6113748812996;8249.422540422172;8681.175288347597;8721.73834784951;8726.432781869158;8728;3297.1262615406936;3376.324735052999;3383.8477268522975;3394.1684287349262;3399.082247793082;6698.497612679069;6707.094542236736;6711.060451536789;6720.059191713164;6724.193096828314;3928.901777455529;3930.21395720857;3933.370660694275;3935.0949281665376;3935.5080614923136;3734.8132593370224;3738.532683414573;3744.737868349244;3747.9250414991548;3762.492063571383;2704.580789977557;2795.7763378929635;2841.303880549279;2880.69752675966;2898.5507246376424;846.0989046665281;848.8514509960733;851.5997481183833;865.6667183134413;866.8786369908217;614.6527212125135;636.6148480169524;641.1088586864263;649.9837504062342;657.2474516678376;4198.202341555095;4300.61294483498;4341.218580655449;4347.5652434756275;4476.194285028726;330.4316575490153;331.17537332043037;332.095041013237;335.18382738032966;335.2038907594463;2888.2634928093203;2896.956545651817;2934;2975.6280464941883;3054.770892183051;7890.210978902064;7975.72084977023;8010.679572817138;8069.798255043554;8099.433039687166;8047.678092876219;8057.798555036171;8133.511989280662;8136.1050284468665;8139.959300203461;6278.529110316653;6355.809325720235;6374.713137908805;6382.457491113215;6466.159399278158;4209.221294060609;4232;4265.573442655709;4275.87172384829;4376.693631445768;7629.923700762922;7672.961635191788;7696.769096927102;7703.691852325845;7708.49894756839;7689;7759.379249660126;7815.96092019559;7853.568053757098;7855.567943763148;5768;6013.819585412444;6029.427204415573;6143.784967526115;6144.109104179958;6550.377714117151;6586.571872828251;6628.966855165693;6631.834204144935;6645.700943457459;6096.725647345791;6133.693315334304;6154.107654390178;6191.102290167811;6242.157308763259;6010;6085.817425477243;6085.969570152298;6103.969480152571;6114.908276375947;5406.864828379322;5430.321209848769;5432.348118225838;5438.048341540294;5438.809641662523;3429.3141371725255;3665.6700896918337;3757.9248415032093;3766.077311058808;3809.809509524568;3668.596454390068;3738.3084129436143;3746.6815320696955;3761.962380376161;3764.265968136187
17:19:38 INFO - PROCESS | 4004 | |4;prototype.html;212.9946651803489;237.50598754590442;240.37022999740682;240.97710717481834;244.7674709026425;4899.828506002291;5014.699118052915;5123.846284611468;5145.691258524491;5204.609654275925;4973.030259099477;5056.595472362212;5077.847664570059;5096.745162741858;5099.719515426659;3659.4447359871947;3681.0981309579192;3688.760230585017;3700.6854417374557;3704.685101766353;3445.6898879100877;3688.8155592220414;3696.704263658901;3703.5185425894597;3704.1480459494333;3627.0750958505546;3628.985912818385;3629.1108678373835;3661.340958627446;3667.614900435456;3634.800085995269;3641.0169254301454;3644.4533320001947;3652.7443078984616;3657.8902632921054;3101.736352410048;3120.953185702216;3125.0937228203793;3130;3134.1851118709073;4947.851564453036;4963.71669032242;4978.377702787152;4979.8008079676765;4988.401391832967;3857.5756666766633;4125.6591607727305;4163.125743593849;4163.687723420756;4278.44380230568;5190;5193;5201.791928322863;5204.34945631796;5205.349331333583;3267.4642917828583;3330.600327960636;3346.330733853239;3350.748693847947;3351.782134161296;5422.864428389322;5445.210444485567;5470.343558772933;5472.179173124063;5483.2049352844015;3367.107716455141;3430.639782822818;3432.9141771455415;3434.6737059979264;3445;3448.827558622109;3475.913102172466;3479.739019573542;3484.8954531364097;3486.9476957845654;4158.126793373425;4162.687798415131;4165.875023749292;4169.56219596944;4170.436991006175;4987.750612469435;5162.638615296968;5242.266082748418;5253.317068781034;5275.419703832576;1189.0962868220215;1196.5273716881784;1196.7427003194355;1196.8204769284503;1198.874118217592;724.2974314914525;734.8860926556392;745.9104907411146;749.9212582678849;755.0788038592908;4520.412346394947;4540.409746732904;4546.52261512543;4572.4055872736335;4583.610393116623;4903.670756390758;4914.827981020647;4934.7300241888415;4936.67911585753;4947.381577302837;4518.960639052971;4570.702904311209;4579.885502862455;4855.708657480563;4978.701277923336;2649.152271273174;2780.457810726889;2827.8303301801957;2831.5186418308936;2881.5101432756483;5692.345380281321;5718.056520674126;5725.828225153252;5751.079827227707;5753.338366087954;5355.973220133875;5564.972175139098;5581.386047534768;5587.078132108157;5598.4961353477565;5591.8881622368135;5615.8596035098635;5720.8283751487525;5731.942680573225;5736.397678243808;5464.836054918359;5473.370562385217;5477.808276710296;5479.0959491683425;5494.450554944474;5308.920366194433;5327.9200811987075;5369.758360873692;5379.085555455504;5401.513863752124;5562.694051827189;5708.315002199763;5746.597738158456;5749.425057494217;5755.992701277343;3351.983240083784;3467.549218601566;3467.8266086696067;3470.340635279289;3482.4776283557667;5425.782968681367;5445.237666726583;5450.155225939986;5453.872989534006;5459.062032978236;5271.630985831109;5278.472152784844;5287.867803304948;5308.46915308466;5312.734363281743;5034.723090230073;5034.823781167642;5228.215767634733;5241;5256.868578285574;5113.948860511347;5163.225516172604;5175.482451754945;5178.792848286026;5180.404253510895;5214.921776173284;5274.235235890697;5276.256346699772;5278.81226723984;5292.285541451854;5033.446320904624;5119.769610367617;5120.078385890575;5190.117679994485;5206.375234971828
17:19:38 INFO - PROCESS | 4004 | |5;yui.html;570.7802496039025;599.1492081244635;607.9726412311445;620.6028141989128;683.388367411167;13875.236861972588;15079.773803392944;15129.773053404208;15181.39274429023;15215.23923803811;13440.93279533601;13738.313084345767;13886.4445422183;13960.022798404116;13996;523.2961666558473;539.0512697652141;649.7985624456422;671.399097807462;671.768239957214;538.4857461124636;543.5787264869718;544.4555444555444;629.4083561452243;640.8301800022998;674.2616834566167;699.2413231643673;699.3915293694492;701.0675801184412;701.291695387657;690.620158912601;691.9135108111486;696.14721965592;697.1146643762419;697.9441644668415;403.94142849286686;410.0834634591685;497.269014548613;507.84764570628664;510.061486864168;13707.246101464416;14144.858551414458;14155.150690958575;14167.71664566703;14213.218272994982;11662.766744665145;11815.527378904919;12045.93977030118;12065.33640649764;12220.938895305555;12674.239545627292;12689.485216302546;12789.488420463264;12851.550195743197;12873.356332183354;4096.303628383182;4106.691998100155;4117.258893399187;4150.667946564267;4199.391088292211;76347.61826190834;78714;79050.60474697647;79592;79908;9696.806063878683;9736.707898763048;9826.606935722633;9828.4102953823;9903;19027.479916215543;19109.140088695764;19119.80880191208;19249.51876203078;19252;6701.32986701326;6794.218664853506;6833.043373927654;7023.68393422287;7051.647417629098;3728.086618778417;3730.421784623388;3732.0856390184576;3748.6251374862295;3830.7701537907815;2716.967552330102;2744.217897899089;2827.6748173959845;2836.602875597418;2857.000049982531;478.09878379255355;478.74626447982627;479.74093989245415;483.784715801465;486.7833813952757;551.7020808763303;589.7228302697725;604.2929772166488;641.8153638278492;641.8395401149712;8435.156484351639;8830.293576513863;8880;8899.866502002475;9131.315151363675;513.3617763506699;567.9687617181012;572.0875204049535;578.5169383564687;594.8988671925783;3979.3793992267388;4096.508418989741;4111.321631930698;4175.707700460938;4181.163767246563;97737;111015.44492277487;111752;113849.43075284736;120724;80383.59808200922;85625.57187214149;89770;90302.54848725714;91499.54250228706;84980.57509712412;85431.14568854356;87029.25941481134;88311.67532487017;88733.55633221792;9159.267258619162;9230.861537076815;9250.907490925005;9292.907070929205;10211.132053775513;46459.767701161276;48843.75578122087;49698.503014969385;49943;50288.99422011466;69524.30475695178;71152;71186.64406677934;71873.4221511244;71980.64009679918;11468.713282167679;11500.82748758786;11568.305901645928;11643.359615221245;11739.413029348327;14477.203753793643;14531.709365812836;14783.926080369529;15001.549953501413;15084;12102.515899363927;12294.262344259016;12657.493700251887;12936.41786119608;13025.869741302466;10692.251542391958;12269.87730122723;12985.220886746447;13190.670233244246;13349.66625834362;11342.20604557706;11719.062475001809;11809.350485723024;12048.216865903689;12297.32364719949;12197;12278.508859645513;12569.308688022247;12668.556600518938;12904.612861614167;11518.481668324777;11749.823752643888;11977.940110299392;12032.217905836093;12141.210821296587
17:19:38 INFO - PROCESS | 4004 | __end_tp_report
17:19:38 INFO - PROCESS | 4004 | __start_cc_report
17:19:38 INFO - PROCESS | 4004 | _x_x_mozilla_cycle_collect,1115
17:19:38 INFO - PROCESS | 4004 | __end_cc_report
17:19:38 INFO - PROCESS | 4004 | __startTimestamp1477009178376__endTimestamp
17:19:38 INFO - PROCESS | 4004 |
17:19:38 INFO - PROCESS | 4004 | ------- Summary: start -------
17:19:38 INFO - PROCESS | 4004 | Number of tests: 6
17:19:38 INFO - PROCESS | 4004 |
17:19:38 INFO - PROCESS | 4004 | [#0] dojo.html Cycles:165 Average:4456.17 Median:4654.12 stddev:546.64 (11.7%) stddev-sans-first:530.45
17:19:38 INFO - PROCESS | 4004 | Values: 2689.2 2706.1 2715.6 2716.3 2719.9 4513.2 4575.9 4607.7 4634.7 4657.0 4505.0 4518.4 4596.4 4674.0 4674.8 4080.8 4083.1 4090.8 4096.2 4117.6 4088.3 4097.1 4099.1 4105.5 4107.8 3988.1 4060.1 4109.4 4110.5 4120.5 3990.1 4061.2 4074.5 4100.5 4105.1 3979.1 3983.5 4021.4 4040.6 4125.7 4524.0 4540.1 4542.9 4560.9 4593.6 4733.2 4765.6 4843.7 4859.9 4876.9 4728.9 4798.2 4799.2 4799.7 4894.4 4394.9 4504.4 4524.4 4555.9 4585.7 4906.6 4915.1 5008.1 5013.1 5015.8 4671.4 4716.4 4726.8 4824.6 4833.3 4713.0 4787.6 4792.9 4810.3 4872.9 4654.1 4751.5 4835.0 4847.9 4848.4 4677.3 4761.4 4804.6 4858.5 4863.0 3188.0 3243.3 3267.5 3283.4 3285.7 3044.3 3046.6 3048.9 3049.1 3051.6 4472.3 4483.0 4493.2 4566.3 4567.8 4754.0 4781.3 4789.2 4810.0 4819.5 4445.7 4482.3 4495.8 4517.8 4530.6 4029.0 4047.3 4064.3 4080.4 4088.7 4894.4 4988.9 5033.5 5047.2 5064.1 4857.9 4865.5 4867.8 4867.8 4885.0 4844.4 4930.8 5013.3 5026.0 5041.1 4691.5 4718.0 4769.4 4784.5 4828.0 4734.8 4761.9 4836.0 4857.6 4944.6 4636.6 4658.7 4664.9 4670.3 4796.9 4595.7 4748.5 4755.9 4762.4 4798.6 4569.0 4641.3 4657.3 4731.6 4732.5 4531.0 4613.7 4689.6 4812.8 4821.1 4531.0 4633.7 4642.8 4649.3 4665.2
17:19:38 INFO - PROCESS | 4004 |
17:19:38 INFO - PROCESS | 4004 | [#1] ext.html Cycles:180 Average:53786.60 Median:205849.50 stddev:107738.99 (52.3%) stddev-sans-first:107967.09
17:19:38 INFO - PROCESS | 4004 | Values: 551.8 566.6 570.8 584.4 597.5 27238.2 38945.0 39923.6 39933.6 40508.6 39129.6 40067.2 40452.6 40622.8 40663.4 6727.8 7866.8 7910.6 8186.7 8201.1 6890.6 9906.0 9974.6 10154.3 10243.9 9934.8 10160.7 10168.1 10173.0 10190.2 6891.4 7046.1 7053.8 10038.4 10117.9 9697.4 10019.1 10038.8 10217.9 10242.6 34427.0 37818.0 37840.4 37844.6 37886.0 3004.9 3071.6 3115.9 3592.8 3660.9 29487.4 29504.9 29818.7 30089.5 30290.5 3467.5 3500.8 3507.0 3532.5 3546.8 289585.0 294841.0 300650.0 302363.0 302953.0 51237.7 51522.5 51630.2 51649.7 51696.2 29105.7 29989.7 53773.5 54077.2 54278.0 11781.2 11833.3 11876.3 11888.3 11889.4 8042.6 8159.8 8515.0 8742.0 8814.3 16708.5 16906.7 17033.1 17047.6 17049.7 6770.2 6801.8 6806.5 6835.3 6926.5 767.8 799.8 820.1 842.6 875.2 26344.7 27456.7 27708.7 27816.9 27980.2 900.2 908.4 919.7 964.6 982.3 13667.7 14352.6 14559.3 14640.6 14674.3 478837.0 481342.0 482341.0 483449.0 486559.0 48728.0 49194.0 49197.0 49202.3 49442.0 984.5 1046.5 1113.2 1146.4 1148.9 27002.3 27706.9 28128.6 28182.4 28424.0 42076.0 42448.0 42497.8 42635.6 42637.0 360527.0 374328.0 382502.4 384204.0 545187.0 35437.6 37445.8 38079.0 38082.6 38115.2 39899.4 42178.4 42502.6 42749.6 43167.1 35164.0 36231.8 36240.1 36718.6 36919.0 36534.7 36641.0 36985.3 37371.0 38120.8 32610.2 33163.2 34083.7 34352.0 34774.5 32346.0 32668.2 33429.8 33572.8 34233.8 24572.1 24625.9 24877.3 24916.8 25381.7
17:19:38 INFO - PROCESS | 4004 |
17:19:38 INFO - PROCESS | 4004 | [#2] jquery.html Cycles:180 Average:25818.65 Median:4830.11 stddev:69177.95 (1432.2%) stddev-sans-first:69345.42
17:19:38 INFO - PROCESS | 4004 | Values: 274.5 296.1 297.5 300.2 309.2 13112.7 17203.3 17429.2 17581.7 17875.0 16693.2 16793.5 16872.7 17132.5 17374.0 5128.8 5290.7 5293.6 5301.0 5313.6 5180.1 5197.4 5332.0 5341.7 5347.9 5291.5 5306.2 5307.4 5309.0 5320.8 4747.1 5156.0 5194.6 5296.5 5298.1 4913.1 4951.2 5054.4 5055.0 5067.0 10164.5 10303.4 10323.1 10457.1 10533.4 1727.3 1789.6 1795.2 1802.9 1983.6 8165.6 8201.6 8347.4 8494.4 8553.5 1636.8 1650.7 1682.4 1683.8 1740.4 82792.0 82848.9 84050.0 85765.0 86253.6 8554.1 8770.8 8885.6 8904.6 8954.2 10265.4 10390.2 10917.8 11168.9 11326.1 6258.2 6261.2 6298.1 6369.3 6448.6 5970.8 6042.8 6091.2 6115.9 6189.7 3652.6 3674.4 3681.2 3719.6 3744.5 1460.8 1468.8 1470.9 1576.8 1588.4 790.1 790.6 837.5 866.6 897.2 17652.0 17666.6 17676.4 17856.5 18244.7 802.2 806.0 808.5 836.8 863.0 9370.3 9867.6 9901.6 9992.6 10076.3 399026.0 415583.0 415649.0 416607.0 421439.0 92332.0 95915.0 96306.0 97514.0 98898.0 902.8 909.9 922.5 922.6 926.5 15581.1 15618.5 15673.0 15797.8 16148.3 33886.8 42335.6 43135.8 43516.8 44727.3 57352.7 58481.0 58788.7 59961.4 60241.7 6733.9 6957.7 7107.9 7118.9 7393.8 12225.6 12662.2 12960.2 13028.5 13136.3 10588.2 10683.2 10786.5 10952.5 10988.3 10195.1 10398.9 10545.5 10870.6 10892.6 10863.9 10903.5 10988.8 10996.6 11185.6 6969.4 6996.9 6998.3 7145.2 7505.5 7136.9 10321.9 10764.2 11006.8 11137.2
17:19:38 INFO - PROCESS | 4004 |
17:19:38 INFO - PROCESS | 4004 | [#3] mootools.html Cycles:180 Average:4332.50 Median:5418.59 stddev:2441.54 (45.1%) stddev-sans-first:2437.24
17:19:38 INFO - PROCESS | 4004 | Values: 1227.7 1246.8 1288.2 1552.2 1554.3 6122.7 6382.9 6562.0 6977.9 6980.3 6301.3 6377.6 6591.2 6790.0 6817.1 1824.4 1853.9 1854.9 1855.6 1859.8 1844.5 1844.7 1859.1 1864.9 1886.8 1621.1 1675.8 1810.9 1857.2 1861.0 1796.1 1820.7 1827.1 1827.5 1829.1 1830.6 1831.3 1847.1 1848.6 1856.3 6432.7 6532.0 6534.3 6543.1 6562.4 3907.6 3982.3 4000.7 4009.0 4013.3 2572.6 2643.7 2674.2 2674.9 2680.5 826.5 850.0 860.0 860.1 863.6 8249.4 8681.2 8721.7 8726.4 8728.0 3297.1 3376.3 3383.8 3394.2 3399.1 6698.5 6707.1 6711.1 6720.1 6724.2 3928.9 3930.2 3933.4 3935.1 3935.5 3734.8 3738.5 3744.7 3747.9 3762.5 2704.6 2795.8 2841.3 2880.7 2898.6 846.1 848.9 851.6 865.7 866.9 614.7 636.6 641.1 650.0 657.2 4198.2 4300.6 4341.2 4347.6 4476.2 330.4 331.2 332.1 335.2 335.2 2888.3 2897.0 2934.0 2975.6 3054.8 7890.2 7975.7 8010.7 8069.8 8099.4 8047.7 8057.8 8133.5 8136.1 8140.0 6278.5 6355.8 6374.7 6382.5 6466.2 4209.2 4232.0 4265.6 4275.9 4376.7 7629.9 7673.0 7696.8 7703.7 7708.5 7689.0 7759.4 7816.0 7853.6 7855.6 5768.0 6013.8 6029.4 6143.8 6144.1 6550.4 6586.6 6629.0 6631.8 6645.7 6096.7 6133.7 6154.1 6191.1 6242.2 6010.0 6085.8 6086.0 6104.0 6114.9 5406.9 5430.3 5432.3 5438.0 5438.8 3429.3 3665.7 3757.9 3766.1 3809.8 3668.6 3738.3 3746.7 3762.0 3764.3
17:19:38 INFO - PROCESS | 4004 |
17:19:38 INFO - PROCESS | 4004 | [#4] prototype.html Cycles:180 Average:4298.62 Median:4983.78 stddev:1378.10 (27.7%) stddev-sans-first:1347.41
17:19:38 INFO - PROCESS | 4004 | Values: 213.0 237.5 240.4 241.0 244.8 4899.8 5014.7 5123.8 5145.7 5204.6 4973.0 5056.6 5077.8 5096.7 5099.7 3659.4 3681.1 3688.8 3700.7 3704.7 3445.7 3688.8 3696.7 3703.5 3704.1 3627.1 3629.0 3629.1 3661.3 3667.6 3634.8 3641.0 3644.5 3652.7 3657.9 3101.7 3121.0 3125.1 3130.0 3134.2 4947.9 4963.7 4978.4 4979.8 4988.4 3857.6 4125.7 4163.1 4163.7 4278.4 5190.0 5193.0 5201.8 5204.3 5205.3 3267.5 3330.6 3346.3 3350.7 3351.8 5422.9 5445.2 5470.3 5472.2 5483.2 3367.1 3430.6 3432.9 3434.7 3445.0 3448.8 3475.9 3479.7 3484.9 3486.9 4158.1 4162.7 4165.9 4169.6 4170.4 4987.8 5162.6 5242.3 5253.3 5275.4 1189.1 1196.5 1196.7 1196.8 1198.9 724.3 734.9 745.9 749.9 755.1 4520.4 4540.4 4546.5 4572.4 4583.6 4903.7 4914.8 4934.7 4936.7 4947.4 4519.0 4570.7 4579.9 4855.7 4978.7 2649.2 2780.5 2827.8 2831.5 2881.5 5692.3 5718.1 5725.8 5751.1 5753.3 5356.0 5565.0 5581.4 5587.1 5598.5 5591.9 5615.9 5720.8 5731.9 5736.4 5464.8 5473.4 5477.8 5479.1 5494.5 5308.9 5327.9 5369.8 5379.1 5401.5 5562.7 5708.3 5746.6 5749.4 5756.0 3352.0 3467.5 3467.8 3470.3 3482.5 5425.8 5445.2 5450.2 5453.9 5459.1 5271.6 5278.5 5287.9 5308.5 5312.7 5034.7 5034.8 5228.2 5241.0 5256.9 5113.9 5163.2 5175.5 5178.8 5180.4 5214.9 5274.2 5276.3 5278.8 5292.3 5033.4 5119.8 5120.1 5190.1 5206.4
17:19:38 INFO - PROCESS | 4004 |
17:19:38 INFO - PROCESS | 4004 | [#5] yui.html Cycles:180 Average:19827.02 Median:4190.28 stddev:28893.40 (689.5%) stddev-sans-first:28938.28
17:19:38 INFO - PROCESS | 4004 | Values: 570.8 599.1 608.0 620.6 683.4 13875.2 15079.8 15129.8 15181.4 15215.2 13440.9 13738.3 13886.4 13960.0 13996.0 523.3 539.1 649.8 671.4 671.8 538.5 543.6 544.5 629.4 640.8 674.3 699.2 699.4 701.1 701.3 690.6 691.9 696.1 697.1 697.9 403.9 410.1 497.3 507.8 510.1 13707.2 14144.9 14155.2 14167.7 14213.2 11662.8 11815.5 12045.9 12065.3 12220.9 12674.2 12689.5 12789.5 12851.6 12873.4 4096.3 4106.7 4117.3 4150.7 4199.4 76347.6 78714.0 79050.6 79592.0 79908.0 9696.8 9736.7 9826.6 9828.4 9903.0 19027.5 19109.1 19119.8 19249.5 19252.0 6701.3 6794.2 6833.0 7023.7 7051.6 3728.1 3730.4 3732.1 3748.6 3830.8 2717.0 2744.2 2827.7 2836.6 2857.0 478.1 478.7 479.7 483.8 486.8 551.7 589.7 604.3 641.8 641.8 8435.2 8830.3 8880.0 8899.9 9131.3 513.4 568.0 572.1 578.5 594.9 3979.4 4096.5 4111.3 4175.7 4181.2 97737.0 111015.4 111752.0 113849.4 120724.0 80383.6 85625.6 89770.0 90302.5 91499.5 84980.6 85431.1 87029.3 88311.7 88733.6 9159.3 9230.9 9250.9 9292.9 10211.1 46459.8 48843.8 49698.5 49943.0 50289.0 69524.3 71152.0 71186.6 71873.4 71980.6 11468.7 11500.8 11568.3 11643.4 11739.4 14477.2 14531.7 14783.9 15001.5 15084.0 12102.5 12294.3 12657.5 12936.4 13025.9 10692.3 12269.9 12985.2 13190.7 13349.7 11342.2 11719.1 11809.4 12048.2 12297.3 12197.0 12278.5 12569.3 12668.6 12904.6 11518.5 11749.8 11977.9 12032.2 12141.2
17:19:38 INFO - PROCESS | 4004 | -------- Summary: end --------
17:19:38 INFO - PROCESS | 4004 |
17:19:39 INFO - TEST-INFO | 4004: exit 0
17:19:39 INFO - TEST-OK | dromaeo_css | took 1120770ms
17:19:39 INFO - TEST-START | kraken
17:19:39 INFO - operating with platform_type : w7_
17:19:39 INFO - Initialising browser for kraken test...
17:19:39 INFO - TEST-INFO | started process 2720 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpqswuct\profile http://localhost:49575/getInfo.html)
17:19:44 INFO - PROCESS | 2720 | __metrics Screen width/height:1600/1200
17:19:44 INFO - PROCESS | 2720 | colorDepth:24
17:19:44 INFO - PROCESS | 2720 | Browser inner width/height: 1010/674
17:19:44 INFO - PROCESS | 2720 | __metrics
17:19:44 INFO - PROCESS | 2720 | 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"
17:19:44 INFO - PROCESS | 2720 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
17:19:45 INFO - PROCESS | 2720 | console.error:
17:19:45 INFO - PROCESS | 2720 | Message: Error: Connection closed before committing the transaction.
17:19:45 INFO - PROCESS | 2720 | Stack:
17:19:45 INFO - PROCESS | 2720 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:937:23
17:19:45 INFO - PROCESS | 2720 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7
17:19:45 INFO - PROCESS | 2720 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11
17:19:45 INFO - PROCESS | 2720 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7
17:19:45 INFO - PROCESS | 2720 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7
17:19:45 INFO - PROCESS | 2720 | TaskImpl_run@resource://gre/modules/Task.jsm:327:15
17:19:45 INFO - PROCESS | 2720 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9
17:19:45 INFO - PROCESS | 2720 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15
17:19:45 INFO - PROCESS | 2720 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5
17:19:45 INFO - PROCESS | 2720 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29
17:19:45 INFO - PROCESS | 2720 | Barrier/this.client.addBlocker/promise13.1%
17:20:42 INFO - TinderboxPrint: I/O read bytes / time
194,457,088 / 2,944,790
17:20:42 INFO - TinderboxPrint: I/O write bytes / time
477,133,824 / 3,562,470
17:20:42 INFO - TinderboxPrint: CPU idle
8,255.0 (86.9%)
17:20:42 INFO - TinderboxPrint: CPU user
1,211.0 (12.7%)
17:20:42 INFO - install - Wall time: 3s; CPU: 12.0%; Read bytes: 0; Write bytes: 36131328; Read time: 0; Write time: 380500
17:20:42 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 194457088; Write bytes: 405289472; Read time: 2944790; Write time: 3152170
17:20:43 INFO - Running post-run listener: _upload_blobber_files
17:20:43 INFO - Blob upload gear active.
17:20:43 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir.
17:20:43 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
17:20:43 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\venv\\Scripts\\blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', 'C:\\slave\\test\\oauth.txt', '-b', 'Fx-Team', '-d', 'C:\\slave\\test\\build\\blobber_upload_dir', '--output-manifest', 'C:\\slave\\test\\build\\uploaded_files.json']
17:20:43 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\venv\Scripts\blobberc.py -u https://blobupload.elasticbeanstalk.com -a C:\slave\test\oauth.txt -b Fx-Team -d C:\slave\test\build\blobber_upload_dir --output-manifest C:\slave\test\build\uploaded_files.json
17:20:43 INFO - (blobuploader) - INFO - Open directory for files ...
17:20:43 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log ...
17:20:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
17:20:43 INFO - (blobuploader) - INFO - Uploading, attempt #1.
17:20:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded
17:20:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
17:20:44 INFO - (blobuploader) - INFO - Done attempting.
17:20:44 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log ...
17:20:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
17:20:44 INFO - (blobuploader) - INFO - Uploading, attempt #1.
17:20:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded
17:20:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
17:20:45 INFO - (blobuploader) - INFO - Done attempting.
17:20:45 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ...
17:20:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
17:20:45 INFO - (blobuploader) - INFO - Uploading, attempt #1.
17:20:52 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
17:20:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
17:20:52 INFO - (blobuploader) - INFO - Done attempting.
17:20:52 INFO - (blobuploader) - INFO - Iteration through files over.
17:20:52 INFO - Return code: 0
17:20:52 INFO - rmtree: C:\slave\test\build\uploaded_files.json
17:20:52 INFO - Using _rmtree_windows ...
17:20:52 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1
17:20:52 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/59d3aaad356d4f7ff8aa50ef2871a49604c68ba9a7f352940fc7b63deac29be5d6a9322112c1735aaf1a0ddcfeec6a6a18ce06571dab32279241c01a3d20f9f4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fb286daf469abe7d0e0d2f7153b10f74d0530df318b97f4b3aa6bc585db1da03984acf7e280d2815e5ca0f1e7b1452e5c86f6cda9aa8792394254f33dbd1f8a5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/323fde27206211f2e2faf02468345b1eb4a7182a27cb8333b4ce822e13453fecab8c5c6d96b9ae725d0c0b8e2cc18f6a079f41e096e2441a543a83e8568dbb96"}
17:20:52 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files
17:20:52 INFO - Writing to file C:\slave\test\properties\blobber_files
17:20:52 INFO - Contents:
17:20:52 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/59d3aaad356d4f7ff8aa50ef2871a49604c68ba9a7f352940fc7b63deac29be5d6a9322112c1735aaf1a0ddcfeec6a6a18ce06571dab32279241c01a3d20f9f4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fb286daf469abe7d0e0d2f7153b10f74d0530df318b97f4b3aa6bc585db1da03984acf7e280d2815e5ca0f1e7b1452e5c86f6cda9aa8792394254f33dbd1f8a5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/323fde27206211f2e2faf02468345b1eb4a7182a27cb8333b4ce822e13453fecab8c5c6d96b9ae725d0c0b8e2cc18f6a079f41e096e2441a543a83e8568dbb96"}
17:20:52 INFO - Running post-run listener: copy_logs_to_upload_dir
17:20:52 INFO - Copying logs to upload dir...
17:20:52 INFO - mkdir: C:\slave\test\build\upload\logs
17:20:52 INFO - Copying logs to upload dir...
17:20:52 INFO - Using _rmtree_windows ...
17:20:52 INFO - Using _rmtree_windows ...
17:20:52 INFO - Using _rmtree_windows ...
17:20:52 INFO - Using _rmtree_windows ...
17:20:52 INFO - Using _rmtree_windows ...
17:20:52 INFO - Using _rmtree_windows ...
17:20:52 INFO - Using _rmtree_windows ...
program finished with exit code 0
elapsedTime=1404.230000
========= master_lag: 0.37 =========
========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 24 secs) (at 2016-10-20 17:20:53.022962) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-20 17:20:53.028536) =========
'cmd' '/C' 'for' '%f' 'in' '(*)' 'do' '@type' '%f'
in dir C:\slave\test\properties (timeout 1200 secs)
watching logfiles {}
argv: ['cmd', '/C', 'for', '%f', 'in', '(*)', 'do', '@type', '%f']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-066
COMSPEC=C:\windows\system32\cmd.exe
CYGWINBASE=C:\cygwin
DCLOCATION=SCL3
DNSSUFFIX=wintest.releng.scl3.mozilla.com
FP_NO_HOST_CHECK=NO
HOMEDRIVE=C:
HOMEPATH=\Users\cltbld
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOCALAPPDATA=C:\Users\cltbld\AppData\Local
LOGONSERVER=\\T-W732-IX-066
MONDIR=C:\Monitor_config\
MOZBUILDDIR=C:\mozilla-build\
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NO_EM_RESTART=1
NUMBER_OF_PROCESSORS=8
OS=Windows_NT
OURDRIVE=C:
PATH=C:\Python24;C:\Python24\Scripts;C:\cygwin\bin;C:\WINDOWS\System32;C:\program files\gnuwin32\bin;C:\WINDOWS;C:\mozilla-build\msys\bin;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\info-zip;C:\mozilla-build\wget;C:\Program Files (x86)\GnuWin32\bin;
PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC
PROCESSOR_ARCHITECTURE=x86
PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel
PROCESSOR_LEVEL=6
PROCESSOR_REVISION=1e05
PROGRAMDATA=C:\ProgramData
PROGRAMFILES=C:\Program Files
PROMPT=$P$G
PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\
PUBLIC=C:\Users\Public
PWD=C:\slave\test\properties
RUNLOGFILE=C:\slave\\runslave.log
SLAVEDIR=C:\slave\
SYSTEMDRIVE=C:
SYSTEMROOT=C:\windows
TEMP=C:\Users\cltbld\AppData\Local\Temp
TEST1=testie
TMP=C:\Users\cltbld\AppData\Local\Temp
USERDOMAIN=T-W732-IX-066
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/59d3aaad356d4f7ff8aa50ef2871a49604c68ba9a7f352940fc7b63deac29be5d6a9322112c1735aaf1a0ddcfeec6a6a18ce06571dab32279241c01a3d20f9f4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fb286daf469abe7d0e0d2f7153b10f74d0530df318b97f4b3aa6bc585db1da03984acf7e280d2815e5ca0f1e7b1452e5c86f6cda9aa8792394254f33dbd1f8a5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/323fde27206211f2e2faf02468345b1eb4a7182a27cb8333b4ce822e13453fecab8c5c6d96b9ae725d0c0b8e2cc18f6a079f41e096e2441a543a83e8568dbb96"}
build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476992154/firefox-52.0a1.en-US.win32.zip
program finished with exit code 0
elapsedTime=0.101000
build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476992154/firefox-52.0a1.en-US.win32.zip'
blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/59d3aaad356d4f7ff8aa50ef2871a49604c68ba9a7f352940fc7b63deac29be5d6a9322112c1735aaf1a0ddcfeec6a6a18ce06571dab32279241c01a3d20f9f4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fb286daf469abe7d0e0d2f7153b10f74d0530df318b97f4b3aa6bc585db1da03984acf7e280d2815e5ca0f1e7b1452e5c86f6cda9aa8792394254f33dbd1f8a5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/323fde27206211f2e2faf02468345b1eb4a7182a27cb8333b4ce822e13453fecab8c5c6d96b9ae725d0c0b8e2cc18f6a079f41e096e2441a543a83e8568dbb96"}'
========= master_lag: 0.01 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-20 17:20:53.141620) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 17:20:53.141924) =========
'rm' '-f' 'oauth.txt'
in dir C:\slave\test\. (timeout 1200 secs)
watching logfiles {}
argv: ['rm', '-f', 'oauth.txt']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-066
COMSPEC=C:\windows\system32\cmd.exe
CYGWINBASE=C:\cygwin
DCLOCATION=SCL3
DNSSUFFIX=wintest.releng.scl3.mozilla.com
FP_NO_HOST_CHECK=NO
HOMEDRIVE=C:
HOMEPATH=\Users\cltbld
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOCALAPPDATA=C:\Users\cltbld\AppData\Local
LOGONSERVER=\\T-W732-IX-066
MONDIR=C:\Monitor_config\
MOZBUILDDIR=C:\mozilla-build\
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NO_EM_RESTART=1
NUMBER_OF_PROCESSORS=8
OS=Windows_NT
OURDRIVE=C:
PATH=C:\Python24;C:\Python24\Scripts;C:\cygwin\bin;C:\WINDOWS\System32;C:\program files\gnuwin32\bin;C:\WINDOWS;C:\mozilla-build\msys\bin;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\info-zip;C:\mozilla-build\wget;C:\Program Files (x86)\GnuWin32\bin;
PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC
PROCESSOR_ARCHITECTURE=x86
PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel
PROCESSOR_LEVEL=6
PROCESSOR_REVISION=1e05
PROGRAMDATA=C:\ProgramData
PROGRAMFILES=C:\Program Files
PROMPT=$P$G
PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\
PUBLIC=C:\Users\Public
PWD=C:\slave\test
RUNLOGFILE=C:\slave\\runslave.log
SLAVEDIR=C:\slave\
SYSTEMDRIVE=C:
SYSTEMROOT=C:\windows
TEMP=C:\Users\cltbld\AppData\Local\Temp
TEST1=testie
TMP=C:\Users\cltbld\AppData\Local\Temp
USERDOMAIN=T-W732-IX-066
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
program finished with exit code 0
elapsedTime=0.101000
========= master_lag: 0.01 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 17:20:53.253207) =========
========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-10-20 17:20:53.253512) =========
========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-10-20 17:20:53.769977) =========
========= Total master_lag: 0.88 =========