builder: fx-team_win7_ix_test-dromaeojs-pgo
slave: t-w732-ix-107
starttime: 1476798855.69
results: success (0)
buildid: 20161018065414
builduid: f487e24eff00404e9cdf56c3205d0506
revision: dc89484d4b45abf442162e5ea2dd46f9de40197d
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-18 06:54:15.693749) =========
master: http://buildbot-master110.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-18 06:54:15.694115) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-18 06:54:15.694410) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-18 06:54:15.845376) =========
========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-18 06:54:15.845636) =========
'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-107
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-107
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-107
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
--06:54:16-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
=> `archiver_client.py'
Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102
Connecting to hg.mozilla.org|63.245.215.25|:443... connected.
WARNING: Certificate verification error for hg.mozilla.org: certificate signature failure
HTTP request sent, awaiting response... 200 Script output follows
Length: 12,179 (12K) [text/x-python]
0K .......... . 100% 22.61 MB/s
06:54:17 (22.61 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=1.382000
========= master_lag: 0.93 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-18 06:54:18.155224) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 17 secs) (at 2016-10-18 06:54:18.155591) =========
'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-107
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-107
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-107
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.105000
========= master_lag: 2.17 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 17 secs) (at 2016-10-18 06:54:35.433020) =========
========= Started 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2016-10-18 06:54:35.433356) =========
'bash' '-c' u'python archiver_client.py mozharness --repo integration/fx-team --rev dc89484d4b45abf442162e5ea2dd46f9de40197d --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 dc89484d4b45abf442162e5ea2dd46f9de40197d --destination scripts --debug']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-107
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-107
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-107
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-18 06:54:35,724 truncating revision to first 12 chars
2016-10-18 06:54:35,724 Setting DEBUG logging.
2016-10-18 06:54:35,724 attempt 1/10
2016-10-18 06:54:35,724 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/dc89484d4b45?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2016-10-18 06:54:37,405 unpacking tar archive at: fx-team-dc89484d4b45/testing/mozharness/
2016-10-18 06:54:37,520 skipping symlink on windows: configs/single_locale/linux32.py
program finished with exit code 0
elapsedTime=2.429000
========= master_lag: 3.29 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2016-10-18 06:54:41.157071) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-18 06:54:41.157353) =========
script_repo_revision: dc89484d4b45abf442162e5ea2dd46f9de40197d
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-18 06:54:41.157754) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-18 06:54:41.158021) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-18 06:54:41.181413) =========
========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 36 secs) (at 2016-10-18 06:54:41.181737) =========
'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-107
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-107
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-107
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
06:54:41 INFO - MultiFileLogger online at 20161018 06:54:41 in C:\slave\test
06:54:41 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
06:54:41 INFO - Dumping config to C:\slave\test\logs\localconfig.json.
06:54:41 INFO - {'append_to_log': False,
06:54:41 INFO - 'base_work_dir': 'C:\\slave\\test',
06:54:41 INFO - 'blob_upload_branch': 'Fx-Team',
06:54:41 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt',
06:54:41 INFO - 'branch': 'Fx-Team',
06:54:41 INFO - 'buildbot_json_path': 'buildprops.json',
06:54:41 INFO - 'config_files': ('talos/windows_config.py',),
06:54:41 INFO - 'default_actions': ('clobber',
06:54:41 INFO - 'read-buildbot-config',
06:54:41 INFO - 'download-and-extract',
06:54:41 INFO - 'populate-webroot',
06:54:41 INFO - 'create-virtualenv',
06:54:41 INFO - 'install',
06:54:41 INFO - 'run-tests'),
06:54:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
06:54:41 INFO - 'download_minidump_stackwalk': True,
06:54:41 INFO - 'download_symbols': 'ondemand',
06:54:41 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python',
06:54:41 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'),
06:54:41 INFO - 'hg': 'c:/mozilla-build/hg/hg',
06:54:41 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python',
06:54:41 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'),
06:54:41 INFO - 'python': 'c:/mozilla-build/python27/python.exe',
06:54:41 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe',
06:54:41 INFO - 'C:/mozilla-build/tooltool.py'),
06:54:41 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe',
06:54:41 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')},
06:54:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
06:54:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
06:54:41 INFO - 'installer_path': 'installer.exe',
06:54:41 INFO - 'log_level': 'info',
06:54:41 INFO - 'log_name': 'talos',
06:54:41 INFO - 'log_to_console': True,
06:54:41 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe',
06:54:41 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe',
06:54:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest',
06:54:41 INFO - 'opt_config_files': (),
06:54:41 INFO - 'pip_index': False,
06:54:41 INFO - 'sps_profile': False,
06:54:41 INFO - 'sps_profile_interval': 0,
06:54:41 INFO - 'suite': 'dromaeojs',
06:54:41 INFO - 'system_bits': '32',
06:54:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
06:54:41 INFO - 'title': 'T-W732-IX-107',
06:54:41 INFO - 'use_talos_json': True,
06:54:41 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'),
06:54:41 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv',
06:54:41 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll',
06:54:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
06:54:41 INFO - 'work_dir': 'build'}
06:54:41 INFO - [mozharness: 2016-10-18 13:54:41.692000Z] Running clobber step.
06:54:41 INFO - Running pre-action listener: _resource_record_pre_action
06:54:41 INFO - Running main action method: clobber
06:54:41 INFO - rmtree: C:\slave\test\build
06:54:41 INFO - Using _rmtree_windows ...
06:54:41 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1
06:57:30 INFO - Running post-action listener: _resource_record_post_action
06:57:30 INFO - [mozharness: 2016-10-18 13:57:30.619000Z] Finished clobber step (success)
06:57:30 INFO - [mozharness: 2016-10-18 13:57:30.619000Z] Running read-buildbot-config step.
06:57:30 INFO - Running pre-action listener: _resource_record_pre_action
06:57:30 INFO - Running main action method: read_buildbot_config
06:57:30 INFO - Using buildbot properties:
06:57:30 INFO - {
06:57:30 INFO - "project": "",
06:57:30 INFO - "product": "firefox",
06:57:30 INFO - "script_repo_revision": "production",
06:57:30 INFO - "builddir": "fx-team_win7_ix_test-dromaeojs",
06:57:30 INFO - "repository": "",
06:57:30 INFO - "buildername": "Windows 7 32-bit fx-team pgo talos dromaeojs",
06:57:30 INFO - "buildid": "20161018065414",
06:57:30 INFO - "stage_platform": "win32-pgo",
06:57:30 INFO - "basedir": "C:\\slave\\test",
06:57:30 INFO - "buildnumber": 233,
06:57:30 INFO - "platform": "win7_ix",
06:57:30 INFO - "master": "http://buildbot-master110.bb.releng.scl3.mozilla.com:8201/",
06:57:30 INFO - "slavebuilddir": "test",
06:57:30 INFO - "branch": "fx-team",
06:57:30 INFO - "revision": "dc89484d4b45abf442162e5ea2dd46f9de40197d",
06:57:30 INFO - "slavename": "t-w732-ix-107",
06:57:30 INFO - "builduid": "f487e24eff00404e9cdf56c3205d0506",
06:57:30 INFO - "repo_path": "integration/fx-team"
06:57:30 INFO - }
06:57:30 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476786616/firefox-52.0a1.en-US.win32.test_packages.json.
06:57:30 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476786616/firefox-52.0a1.en-US.win32.zip.
06:57:30 INFO - Running post-action listener: _resource_record_post_action
06:57:30 INFO - [mozharness: 2016-10-18 13:57:30.622000Z] Finished read-buildbot-config step (success)
06:57:30 INFO - [mozharness: 2016-10-18 13:57:30.622000Z] Running download-and-extract step.
06:57:30 INFO - Running pre-action listener: _resource_record_pre_action
06:57:30 INFO - Running main action method: download_and_extract
06:57:30 INFO - mkdir: C:\slave\test\build\tests
06:57:30 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')]}
06:57:30 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476786616/firefox-52.0a1.en-US.win32.test_packages.json
06:57:30 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476786616/firefox-52.0a1.en-US.win32.test_packages.json to C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json
06:57:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476786616/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
06:57:31 INFO - Downloaded 1218 bytes.
06:57:31 INFO - Reading from file C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json
06:57:31 INFO - Using the following test package requirements:
06:57:31 INFO - {u'common': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
06:57:31 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
06:57:31 INFO - u'firefox-52.0a1.en-US.win32.cppunittest.tests.zip'],
06:57:31 INFO - u'gtest': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
06:57:31 INFO - u'jittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
06:57:31 INFO - u'jsshell-win32.zip'],
06:57:31 INFO - u'mochitest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
06:57:31 INFO - u'firefox-52.0a1.en-US.win32.mochitest.tests.zip'],
06:57:31 INFO - u'mozbase': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
06:57:31 INFO - u'reftest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
06:57:31 INFO - u'firefox-52.0a1.en-US.win32.reftest.tests.zip'],
06:57:31 INFO - u'talos': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
06:57:31 INFO - u'firefox-52.0a1.en-US.win32.talos.tests.zip'],
06:57:31 INFO - u'web-platform': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
06:57:31 INFO - u'firefox-52.0a1.en-US.win32.web-platform.tests.zip'],
06:57:31 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
06:57:31 INFO - u'firefox-52.0a1.en-US.win32.xpcshell.tests.zip']}
06:57:31 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip'] for test suite category: common
06:57:31 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/1476786616/firefox-52.0a1.en-US.win32.common.tests.zip
06:57:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476786616/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1
06:57:31 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476786616/firefox-52.0a1.en-US.win32.common.tests.zip into memory
06:57:31 INFO - Http code: 200
06:57:31 INFO - via: 1.1 76bce8bb4fbd102fc0b3aa2e41094b79.cloudfront.net (CloudFront)
06:57:31 INFO - x-amz-cf-id: N24l2tM1UAdbb9pn91Py4XYPpMpAnMrUMQqhJ6dgmQqVNxSgZrSBuA==
06:57:31 INFO - x-amz-replication-status: COMPLETED
06:57:31 INFO - x-amz-version-id: EhbTUiW.GlyjH7b1xGpDynzanB2KARhs
06:57:32 INFO - Expected file size: 17241912
06:57:32 INFO - Obtained file size: 17241912
06:58:09 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
06:58:09 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/1476786616/firefox-52.0a1.en-US.win32.common.tests.zip
06:58:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476786616/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1
06:58:09 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476786616/firefox-52.0a1.en-US.win32.common.tests.zip into memory
06:58:09 INFO - Http code: 200
06:58:09 INFO - via: 1.1 336f0e6ef9a3462f682d6ca49029b665.cloudfront.net (CloudFront)
06:58:09 INFO - x-amz-cf-id: 0tezEnqlxTQMkX4fHc0ynKWE39Jyycxyo7aZiFm6KFNVaXA01Q-_4Q==
06:58:09 INFO - x-amz-replication-status: COMPLETED
06:58:09 INFO - x-amz-version-id: EhbTUiW.GlyjH7b1xGpDynzanB2KARhs
06:58:09 INFO - Expected file size: 17241912
06:58:09 INFO - Obtained file size: 17241912
06:58:20 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/1476786616/firefox-52.0a1.en-US.win32.talos.tests.zip
06:58:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476786616/firefox-52.0a1.en-US.win32.talos.tests.zip'}, attempt #1
06:58:20 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476786616/firefox-52.0a1.en-US.win32.talos.tests.zip into memory
06:58:20 INFO - Http code: 200
06:58:20 INFO - via: 1.1 d8f42fc9558e3e49ebfdf8834baeb756.cloudfront.net (CloudFront)
06:58:20 INFO - x-amz-cf-id: McEIbMD2dCUMTdzOrsAX-iDP1GPStFlvCmWA9lTSQ7JNkjY-XHQfvw==
06:58:20 INFO - x-amz-replication-status: COMPLETED
06:58:20 INFO - x-amz-version-id: KpFnLVRf_jW7u4T8KYmqOrTxChuMYPm0
06:58:20 INFO - Expected file size: 14409028
06:58:20 INFO - Obtained file size: 14409028
06:58:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
06:58:22 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476786616/firefox-52.0a1.en-US.win32.zip
06:58:22 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476786616/firefox-52.0a1.en-US.win32.zip to C:\slave\test\build\firefox-52.0a1.en-US.win32.zip
06:58:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476786616/firefox-52.0a1.en-US.win32.zip', 'file_name': 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.zip'}, attempt #1
06:58:26 INFO - Downloaded 54248074 bytes.
06:58:26 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476786616/firefox-52.0a1.en-US.win32.zip
06:58:26 INFO - mkdir: C:\slave\test\properties
06:58:26 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url
06:58:26 INFO - Writing to file C:\slave\test\properties\build_url
06:58:26 INFO - Contents:
06:58:26 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476786616/firefox-52.0a1.en-US.win32.zip
06:58:26 INFO - Running post-action listener: _resource_record_post_action
06:58:26 INFO - Running post-action listener: set_extra_try_arguments
06:58:26 INFO - [mozharness: 2016-10-18 13:58:26.371000Z] Finished download-and-extract step (success)
06:58:26 INFO - [mozharness: 2016-10-18 13:58:26.371000Z] Running populate-webroot step.
06:58:26 INFO - Running pre-action listener: _resource_record_pre_action
06:58:26 INFO - Running main action method: populate_webroot
06:58:26 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']},
06:58:26 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']},
06:58:26 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome',
06:58:26 INFO - u'--tppagecycles',
06:58:26 INFO - u'7'],
06:58:26 INFO - u'tests': [u'tsvgm']}},
06:58:26 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'],
06:58:26 INFO - u'tests': [u'tresize', u'tcanvasmark']},
06:58:26 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']},
06:58:26 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'],
06:58:26 INFO - u'tests': [u'dromaeo_css', u'kraken']},
06:58:26 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']},
06:58:26 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
06:58:26 INFO - u'talos_options': [u'--disable-e10s'],
06:58:26 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
06:58:26 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
06:58:26 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
06:58:26 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
06:58:26 INFO - u'talos_options': [u'--disable-e10s'],
06:58:26 INFO - u'tests': [u'damp', u'tps']},
06:58:26 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
06:58:26 INFO - u'tests': [u'damp', u'tps']},
06:58:26 INFO - u'g3': {u'talos_options': [u'--disable-e10s'],
06:58:26 INFO - u'tests': [u'dromaeo_dom']},
06:58:26 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']},
06:58:26 INFO - u'g4': {u'talos_options': [u'--disable-e10s'],
06:58:26 INFO - u'tests': [u'basic_compositor_video']},
06:58:26 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']},
06:58:26 INFO - u'other': {u'talos_options': [u'--disable-e10s'],
06:58:26 INFO - u'tests': [u'a11yr',
06:58:26 INFO - u'ts_paint',
06:58:26 INFO - u'tpaint',
06:58:26 INFO - u'sessionrestore',
06:58:26 INFO - u'sessionrestore_no_auto_restore',
06:58:26 INFO - u'tabpaint']},
06:58:26 INFO - u'other-e10s': {u'tests': [u'a11yr',
06:58:26 INFO - u'ts_paint',
06:58:26 INFO - u'tpaint',
06:58:26 INFO - u'sessionrestore',
06:58:26 INFO - u'sessionrestore_no_auto_restore',
06:58:26 INFO - u'tabpaint']},
06:58:26 INFO - u'other-e10s_l64': {u'tests': [u'a11yr',
06:58:26 INFO - u'ts_paint',
06:58:26 INFO - u'tpaint',
06:58:26 INFO - u'sessionrestore',
06:58:26 INFO - u'sessionrestore_no_auto_restore',
06:58:26 INFO - u'tabpaint']},
06:58:26 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr',
06:58:26 INFO - u'ts_paint',
06:58:26 INFO - u'tpaint',
06:58:26 INFO - u'sessionrestore',
06:58:26 INFO - u'sessionrestore_no_auto_restore',
06:58:26 INFO - u'tabpaint']},
06:58:26 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'],
06:58:26 INFO - u'tests': [u'a11yr',
06:58:26 INFO - u'ts_paint',
06:58:26 INFO - u'tpaint',
06:58:26 INFO - u'sessionrestore',
06:58:26 INFO - u'sessionrestore_no_auto_restore',
06:58:26 INFO - u'tabpaint']},
06:58:26 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'],
06:58:26 INFO - u'tests': [u'a11yr',
06:58:26 INFO - u'ts_paint',
06:58:26 INFO - u'tpaint',
06:58:26 INFO - u'sessionrestore',
06:58:26 INFO - u'sessionrestore_no_auto_restore',
06:58:26 INFO - u'tabpaint']},
06:58:26 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'],
06:58:26 INFO - u'tests': [u'tsvgx',
06:58:26 INFO - u'tsvgr_opacity',
06:58:26 INFO - u'tart',
06:58:26 INFO - u'tscrollx',
06:58:26 INFO - u'cart']},
06:58:26 INFO - u'svgr-e10s': {u'tests': [u'tsvgx',
06:58:26 INFO - u'tsvgr_opacity',
06:58:26 INFO - u'tart',
06:58:26 INFO - u'tscrollx',
06:58:26 INFO - u'cart']},
06:58:26 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
06:58:26 INFO - u'talos_options': [u'--disable-e10s'],
06:58:26 INFO - u'tests': [u'tp5o']},
06:58:26 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
06:58:26 INFO - u'tests': [u'tp5o']},
06:58:26 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
06:58:26 INFO - u'talos_options': [u'--disable-e10s',
06:58:26 INFO - u'--xperf_path',
06:58:26 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
06:58:26 INFO - u'tests': [u'tp5n']},
06:58:26 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
06:58:26 INFO - u'talos_options': [u'--xperf_path',
06:58:26 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
06:58:26 INFO - u'tests': [u'tp5n']}},
06:58:26 INFO - u'talos.zip': {u'path': u'',
06:58:26 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}}
06:58:26 INFO - Running post-action listener: _resource_record_post_action
06:58:26 INFO - [mozharness: 2016-10-18 13:58:26.466000Z] Finished populate-webroot step (success)
06:58:26 INFO - [mozharness: 2016-10-18 13:58:26.467000Z] Running create-virtualenv step.
06:58:26 INFO - Running pre-action listener: _resource_record_pre_action
06:58:26 INFO - Running main action method: create_virtualenv
06:58:26 INFO - Creating virtualenv C:\slave\test\build/venv
06:58:26 INFO - mkdir: C:\slave\test\build\venv\Scripts
06:58:26 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll
06:58:26 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'!
06:58:26 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
06:58:26 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv
06:58:29 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe
06:58:37 INFO - Installing distribute......................................................................................................................................................................................done.
06:58:39 INFO - Return code: 0
06:58:39 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version']
06:58:39 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version
06:58:39 INFO - Reading from file tmpfile_stdout
06:58:39 INFO - Output received:
06:58:39 INFO - pip 0.8.2 from c:\slave\test\build\venv\lib\site-packages\pip-0.8.2-py2.7.egg (python 2.7)
06:58:39 INFO - Using _rmtree_windows ...
06:58:39 INFO - Using _rmtree_windows ...
06:58:39 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv
06:58:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
06:58:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
06:58:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
06:58:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
06:58:39 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 0x01791640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01835E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0173B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01842E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01904520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-107', 'USERDOMAIN': 'T-W732-IX-107', '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-107', '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
06:58:39 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
06:58:39 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
06:58:39 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
06:58:39 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
06:58:39 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
06:58:39 INFO - 'COMPUTERNAME': 'T-W732-IX-107',
06:58:39 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
06:58:39 INFO - 'CYGWINBASE': 'C:\\cygwin',
06:58:39 INFO - 'DCLOCATION': 'SCL3',
06:58:39 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
06:58:39 INFO - 'FP_NO_HOST_CHECK': 'NO',
06:58:39 INFO - 'HOMEDRIVE': 'C:',
06:58:39 INFO - 'HOMEPATH': '\\Users\\cltbld',
06:58:39 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
06:58:39 INFO - 'KTS_VERSION': '1.19c',
06:58:39 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
06:58:39 INFO - 'LOGONSERVER': '\\\\T-W732-IX-107',
06:58:39 INFO - 'MONDIR': 'C:\\Monitor_config\\',
06:58:39 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
06:58:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
06:58:39 INFO - 'MOZ_NO_REMOTE': '1',
06:58:39 INFO - 'NO_EM_RESTART': '1',
06:58:39 INFO - 'NUMBER_OF_PROCESSORS': '8',
06:58:39 INFO - 'OS': 'Windows_NT',
06:58:39 INFO - 'OURDRIVE': 'C:',
06:58:39 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
06:58:39 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
06:58:39 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
06:58:39 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
06:58:39 INFO - 'PROCESSOR_LEVEL': '6',
06:58:39 INFO - 'PROCESSOR_REVISION': '1e05',
06:58:39 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
06:58:39 INFO - 'PROGRAMFILES': 'C:\\Program Files',
06:58:39 INFO - 'PROMPT': '$P$G',
06:58:39 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
06:58:39 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
06:58:39 INFO - 'PUBLIC': 'C:\\Users\\Public',
06:58:39 INFO - 'PWD': 'C:\\slave\\test',
06:58:39 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
06:58:39 INFO - 'SLAVEDIR': 'C:\\slave\\',
06:58:39 INFO - 'SYSTEMDRIVE': 'C:',
06:58:39 INFO - 'SYSTEMROOT': 'C:\\windows',
06:58:39 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:58:39 INFO - 'TEST1': 'testie',
06:58:39 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:58:39 INFO - 'USERDOMAIN': 'T-W732-IX-107',
06:58:39 INFO - 'USERNAME': 'cltbld',
06:58:39 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
06:58:39 INFO - 'WINDIR': 'C:\\windows',
06:58:39 INFO - 'WINDOWS_TRACING_FLAGS': '3',
06:58:39 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
06:58:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
06:58:40 INFO - Ignoring indexes: http://pypi.python.org/simple/
06:58:40 INFO - Downloading/unpacking pip>=1.5
06:58:44 INFO - Running setup.py egg_info for package pip
06:58:44 INFO - warning: no files found matching 'pip\cacert.pem'
06:58:44 INFO - warning: no files found matching '*.html' under directory 'docs'
06:58:44 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
06:58:44 INFO - no previously-included directories found matching 'docs\_build\_sources'
06:58:44 INFO - Installing collected packages: pip
06:58:44 INFO - Found existing installation: pip 0.8.2
06:58:44 INFO - Uninstalling pip:
06:58:44 INFO - Successfully uninstalled pip
06:58:44 INFO - Running setup.py install for pip
06:58:44 INFO - warning: no files found matching 'pip\cacert.pem'
06:58:44 INFO - warning: no files found matching '*.html' under directory 'docs'
06:58:44 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
06:58:44 INFO - no previously-included directories found matching 'docs\_build\_sources'
06:58:44 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts
06:58:44 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts
06:58:44 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts
06:58:44 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts
06:58:44 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts
06:58:44 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts
06:58:44 INFO - Successfully installed pip
06:58:44 INFO - Cleaning up...
06:58:44 INFO - Return code: 0
06:58:44 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv
06:58:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
06:58:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
06:58:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
06:58:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
06:58:44 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 0x01791640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01835E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0173B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01842E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01904520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-107', 'USERDOMAIN': 'T-W732-IX-107', '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-107', '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
06:58:44 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
06:58:44 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
06:58:44 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
06:58:44 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
06:58:44 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
06:58:44 INFO - 'COMPUTERNAME': 'T-W732-IX-107',
06:58:44 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
06:58:44 INFO - 'CYGWINBASE': 'C:\\cygwin',
06:58:44 INFO - 'DCLOCATION': 'SCL3',
06:58:44 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
06:58:44 INFO - 'FP_NO_HOST_CHECK': 'NO',
06:58:44 INFO - 'HOMEDRIVE': 'C:',
06:58:44 INFO - 'HOMEPATH': '\\Users\\cltbld',
06:58:44 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
06:58:44 INFO - 'KTS_VERSION': '1.19c',
06:58:44 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
06:58:44 INFO - 'LOGONSERVER': '\\\\T-W732-IX-107',
06:58:44 INFO - 'MONDIR': 'C:\\Monitor_config\\',
06:58:44 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
06:58:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
06:58:44 INFO - 'MOZ_NO_REMOTE': '1',
06:58:44 INFO - 'NO_EM_RESTART': '1',
06:58:44 INFO - 'NUMBER_OF_PROCESSORS': '8',
06:58:44 INFO - 'OS': 'Windows_NT',
06:58:44 INFO - 'OURDRIVE': 'C:',
06:58:44 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
06:58:44 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
06:58:44 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
06:58:44 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
06:58:44 INFO - 'PROCESSOR_LEVEL': '6',
06:58:44 INFO - 'PROCESSOR_REVISION': '1e05',
06:58:44 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
06:58:44 INFO - 'PROGRAMFILES': 'C:\\Program Files',
06:58:44 INFO - 'PROMPT': '$P$G',
06:58:44 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
06:58:44 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
06:58:44 INFO - 'PUBLIC': 'C:\\Users\\Public',
06:58:44 INFO - 'PWD': 'C:\\slave\\test',
06:58:44 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
06:58:44 INFO - 'SLAVEDIR': 'C:\\slave\\',
06:58:44 INFO - 'SYSTEMDRIVE': 'C:',
06:58:44 INFO - 'SYSTEMROOT': 'C:\\windows',
06:58:44 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:58:44 INFO - 'TEST1': 'testie',
06:58:44 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:58:44 INFO - 'USERDOMAIN': 'T-W732-IX-107',
06:58:44 INFO - 'USERNAME': 'cltbld',
06:58:44 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
06:58:44 INFO - 'WINDIR': 'C:\\windows',
06:58:44 INFO - 'WINDOWS_TRACING_FLAGS': '3',
06:58:44 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
06:58:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
06:58:46 INFO - Ignoring indexes: https://pypi.python.org/simple/
06:58:46 INFO - Downloading/unpacking psutil>=3.1.1
06:58:46 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
06:58:46 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
06:58:47 INFO - Installing collected packages: psutil
06:58:47 INFO - Successfully installed psutil
06:58:47 INFO - Cleaning up...
06:58:47 INFO - Return code: 0
06:58:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv
06:58:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
06:58:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
06:58:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
06:58:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
06:58:47 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01791640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01835E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0173B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01842E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01904520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-107', 'USERDOMAIN': 'T-W732-IX-107', '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-107', '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
06:58:47 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in C:\slave\test\build
06:58:47 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3
06:58:47 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
06:58:47 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
06:58:47 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
06:58:47 INFO - 'COMPUTERNAME': 'T-W732-IX-107',
06:58:47 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
06:58:47 INFO - 'CYGWINBASE': 'C:\\cygwin',
06:58:47 INFO - 'DCLOCATION': 'SCL3',
06:58:47 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
06:58:47 INFO - 'FP_NO_HOST_CHECK': 'NO',
06:58:47 INFO - 'HOMEDRIVE': 'C:',
06:58:47 INFO - 'HOMEPATH': '\\Users\\cltbld',
06:58:47 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
06:58:47 INFO - 'KTS_VERSION': '1.19c',
06:58:47 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
06:58:47 INFO - 'LOGONSERVER': '\\\\T-W732-IX-107',
06:58:47 INFO - 'MONDIR': 'C:\\Monitor_config\\',
06:58:47 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
06:58:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
06:58:47 INFO - 'MOZ_NO_REMOTE': '1',
06:58:47 INFO - 'NO_EM_RESTART': '1',
06:58:47 INFO - 'NUMBER_OF_PROCESSORS': '8',
06:58:47 INFO - 'OS': 'Windows_NT',
06:58:47 INFO - 'OURDRIVE': 'C:',
06:58:47 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
06:58:47 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
06:58:47 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
06:58:47 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
06:58:47 INFO - 'PROCESSOR_LEVEL': '6',
06:58:47 INFO - 'PROCESSOR_REVISION': '1e05',
06:58:47 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
06:58:47 INFO - 'PROGRAMFILES': 'C:\\Program Files',
06:58:47 INFO - 'PROMPT': '$P$G',
06:58:47 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
06:58:47 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
06:58:47 INFO - 'PUBLIC': 'C:\\Users\\Public',
06:58:47 INFO - 'PWD': 'C:\\slave\\test',
06:58:47 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
06:58:47 INFO - 'SLAVEDIR': 'C:\\slave\\',
06:58:47 INFO - 'SYSTEMDRIVE': 'C:',
06:58:47 INFO - 'SYSTEMROOT': 'C:\\windows',
06:58:47 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:58:47 INFO - 'TEST1': 'testie',
06:58:47 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:58:47 INFO - 'USERDOMAIN': 'T-W732-IX-107',
06:58:47 INFO - 'USERNAME': 'cltbld',
06:58:47 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
06:58:47 INFO - 'WINDIR': 'C:\\windows',
06:58:47 INFO - 'WINDOWS_TRACING_FLAGS': '3',
06:58:47 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
06:58:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
06:58:48 INFO - Ignoring indexes: https://pypi.python.org/simple/
06:58:48 INFO - Downloading/unpacking mozsystemmonitor==0.3
06:58: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
06:58: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
06:58:49 INFO - Downloading mozsystemmonitor-0.3.tar.gz
06:58:49 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor
06:58:49 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)
06:58:49 INFO - Installing collected packages: mozsystemmonitor
06:58:49 INFO - Running setup.py install for mozsystemmonitor
06:58:49 INFO - Successfully installed mozsystemmonitor
06:58:49 INFO - Cleaning up...
06:58:49 INFO - Return code: 0
06:58:49 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv
06:58:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
06:58:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
06:58:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
06:58:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
06:58:49 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 0x01791640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01835E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0173B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01842E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01904520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-107', 'USERDOMAIN': 'T-W732-IX-107', '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-107', '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
06:58:49 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
06:58:49 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
06:58:49 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
06:58:49 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
06:58:49 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
06:58:49 INFO - 'COMPUTERNAME': 'T-W732-IX-107',
06:58:49 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
06:58:49 INFO - 'CYGWINBASE': 'C:\\cygwin',
06:58:49 INFO - 'DCLOCATION': 'SCL3',
06:58:49 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
06:58:49 INFO - 'FP_NO_HOST_CHECK': 'NO',
06:58:49 INFO - 'HOMEDRIVE': 'C:',
06:58:49 INFO - 'HOMEPATH': '\\Users\\cltbld',
06:58:49 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
06:58:49 INFO - 'KTS_VERSION': '1.19c',
06:58:49 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
06:58:49 INFO - 'LOGONSERVER': '\\\\T-W732-IX-107',
06:58:49 INFO - 'MONDIR': 'C:\\Monitor_config\\',
06:58:49 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
06:58:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
06:58:49 INFO - 'MOZ_NO_REMOTE': '1',
06:58:49 INFO - 'NO_EM_RESTART': '1',
06:58:49 INFO - 'NUMBER_OF_PROCESSORS': '8',
06:58:49 INFO - 'OS': 'Windows_NT',
06:58:49 INFO - 'OURDRIVE': 'C:',
06:58:49 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
06:58:49 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
06:58:49 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
06:58:49 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
06:58:49 INFO - 'PROCESSOR_LEVEL': '6',
06:58:49 INFO - 'PROCESSOR_REVISION': '1e05',
06:58:49 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
06:58:49 INFO - 'PROGRAMFILES': 'C:\\Program Files',
06:58:49 INFO - 'PROMPT': '$P$G',
06:58:49 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
06:58:49 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
06:58:49 INFO - 'PUBLIC': 'C:\\Users\\Public',
06:58:49 INFO - 'PWD': 'C:\\slave\\test',
06:58:49 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
06:58:49 INFO - 'SLAVEDIR': 'C:\\slave\\',
06:58:49 INFO - 'SYSTEMDRIVE': 'C:',
06:58:49 INFO - 'SYSTEMROOT': 'C:\\windows',
06:58:49 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:58:49 INFO - 'TEST1': 'testie',
06:58:49 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:58:49 INFO - 'USERDOMAIN': 'T-W732-IX-107',
06:58:49 INFO - 'USERNAME': 'cltbld',
06:58:49 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
06:58:49 INFO - 'WINDIR': 'C:\\windows',
06:58:49 INFO - 'WINDOWS_TRACING_FLAGS': '3',
06:58:49 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
06:58:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
06:58:50 INFO - Ignoring indexes: https://pypi.python.org/simple/
06:58:50 INFO - Downloading/unpacking jsonschema==2.5.1
06:58:50 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
06:58:50 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
06:58:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl
06:58:51 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1)
06:58:51 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
06:58:51 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
06:58:51 INFO - Downloading functools32-3.2.3-2.tar.gz
06:58:51 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32
06:58:51 INFO - warning: no files found matching '*.txt'
06:58:51 INFO - no previously-included directories found matching 'build'
06:58:51 INFO - no previously-included directories found matching 'dist'
06:58:51 INFO - no previously-included directories found matching '.git*'
06:58:51 INFO - Installing collected packages: jsonschema, functools32
06:58:51 INFO - Running setup.py install for functools32
06:58:51 INFO - warning: no files found matching '*.txt'
06:58:51 INFO - no previously-included directories found matching 'build'
06:58:51 INFO - no previously-included directories found matching 'dist'
06:58:51 INFO - no previously-included directories found matching '.git*'
06:58:51 INFO - Successfully installed jsonschema functools32
06:58:51 INFO - Cleaning up...
06:58:52 INFO - Return code: 0
06:58:52 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv
06:58:52 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')]}
06:58:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
06:58:52 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')]}
06:58:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
06:58:52 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 0x01791640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01835E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0173B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01842E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01904520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-107', 'USERDOMAIN': 'T-W732-IX-107', '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-107', '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
06:58:52 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
06:58:52 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
06:58:52 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
06:58:52 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
06:58:52 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
06:58:52 INFO - 'COMPUTERNAME': 'T-W732-IX-107',
06:58:52 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
06:58:52 INFO - 'CYGWINBASE': 'C:\\cygwin',
06:58:52 INFO - 'DCLOCATION': 'SCL3',
06:58:52 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
06:58:52 INFO - 'FP_NO_HOST_CHECK': 'NO',
06:58:52 INFO - 'HOMEDRIVE': 'C:',
06:58:52 INFO - 'HOMEPATH': '\\Users\\cltbld',
06:58:52 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
06:58:52 INFO - 'KTS_VERSION': '1.19c',
06:58:52 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
06:58:52 INFO - 'LOGONSERVER': '\\\\T-W732-IX-107',
06:58:52 INFO - 'MONDIR': 'C:\\Monitor_config\\',
06:58:52 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
06:58:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
06:58:52 INFO - 'MOZ_NO_REMOTE': '1',
06:58:52 INFO - 'NO_EM_RESTART': '1',
06:58:52 INFO - 'NUMBER_OF_PROCESSORS': '8',
06:58:52 INFO - 'OS': 'Windows_NT',
06:58:52 INFO - 'OURDRIVE': 'C:',
06:58:52 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
06:58:52 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
06:58:52 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
06:58:52 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
06:58:52 INFO - 'PROCESSOR_LEVEL': '6',
06:58:52 INFO - 'PROCESSOR_REVISION': '1e05',
06:58:52 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
06:58:52 INFO - 'PROGRAMFILES': 'C:\\Program Files',
06:58:52 INFO - 'PROMPT': '$P$G',
06:58:52 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
06:58:52 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
06:58:52 INFO - 'PUBLIC': 'C:\\Users\\Public',
06:58:52 INFO - 'PWD': 'C:\\slave\\test',
06:58:52 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
06:58:52 INFO - 'SLAVEDIR': 'C:\\slave\\',
06:58:52 INFO - 'SYSTEMDRIVE': 'C:',
06:58:52 INFO - 'SYSTEMROOT': 'C:\\windows',
06:58:52 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:58:52 INFO - 'TEST1': 'testie',
06:58:52 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:58:52 INFO - 'USERDOMAIN': 'T-W732-IX-107',
06:58:52 INFO - 'USERNAME': 'cltbld',
06:58:52 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
06:58:52 INFO - 'WINDIR': 'C:\\windows',
06:58:52 INFO - 'WINDOWS_TRACING_FLAGS': '3',
06:58:52 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
06:58:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
06:58:52 INFO - Ignoring indexes: https://pypi.python.org/simple/
06:58:52 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages
06:58:52 INFO - Cleaning up...
06:58:52 INFO - Return code: 0
06:58:52 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv
06:58:52 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')]}
06:58:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
06:58:52 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')]}
06:58:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
06:58:52 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 0x01791640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01835E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0173B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01842E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01904520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-107', 'USERDOMAIN': 'T-W732-IX-107', '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-107', '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
06:58:52 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
06:58:52 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
06:58:52 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
06:58:52 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
06:58:52 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
06:58:52 INFO - 'COMPUTERNAME': 'T-W732-IX-107',
06:58:52 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
06:58:52 INFO - 'CYGWINBASE': 'C:\\cygwin',
06:58:52 INFO - 'DCLOCATION': 'SCL3',
06:58:52 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
06:58:52 INFO - 'FP_NO_HOST_CHECK': 'NO',
06:58:52 INFO - 'HOMEDRIVE': 'C:',
06:58:52 INFO - 'HOMEPATH': '\\Users\\cltbld',
06:58:52 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
06:58:52 INFO - 'KTS_VERSION': '1.19c',
06:58:52 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
06:58:52 INFO - 'LOGONSERVER': '\\\\T-W732-IX-107',
06:58:52 INFO - 'MONDIR': 'C:\\Monitor_config\\',
06:58:52 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
06:58:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
06:58:52 INFO - 'MOZ_NO_REMOTE': '1',
06:58:52 INFO - 'NO_EM_RESTART': '1',
06:58:52 INFO - 'NUMBER_OF_PROCESSORS': '8',
06:58:52 INFO - 'OS': 'Windows_NT',
06:58:52 INFO - 'OURDRIVE': 'C:',
06:58:52 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
06:58:52 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
06:58:52 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
06:58:52 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
06:58:52 INFO - 'PROCESSOR_LEVEL': '6',
06:58:52 INFO - 'PROCESSOR_REVISION': '1e05',
06:58:52 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
06:58:52 INFO - 'PROGRAMFILES': 'C:\\Program Files',
06:58:52 INFO - 'PROMPT': '$P$G',
06:58:52 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
06:58:52 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
06:58:52 INFO - 'PUBLIC': 'C:\\Users\\Public',
06:58:52 INFO - 'PWD': 'C:\\slave\\test',
06:58:52 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
06:58:52 INFO - 'SLAVEDIR': 'C:\\slave\\',
06:58:52 INFO - 'SYSTEMDRIVE': 'C:',
06:58:52 INFO - 'SYSTEMROOT': 'C:\\windows',
06:58:52 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:58:52 INFO - 'TEST1': 'testie',
06:58:52 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:58:52 INFO - 'USERDOMAIN': 'T-W732-IX-107',
06:58:52 INFO - 'USERNAME': 'cltbld',
06:58:52 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
06:58:52 INFO - 'WINDIR': 'C:\\windows',
06:58:52 INFO - 'WINDOWS_TRACING_FLAGS': '3',
06:58:52 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
06:58:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
06:58:52 INFO - Ignoring indexes: https://pypi.python.org/simple/
06:58:52 INFO - Downloading/unpacking blobuploader==1.2.4
06:58:52 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
06:58:52 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
06:58:53 INFO - Downloading blobuploader-1.2.4.tar.gz
06:58:53 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader
06:58:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4)
06:58:53 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
06:58:53 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
06:58:54 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests
06:58:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4)
06:58:54 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
06:58:54 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
06:58:54 INFO - Downloading docopt-0.6.1.tar.gz
06:58:54 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt
06:58:54 INFO - Installing collected packages: blobuploader, requests, docopt
06:58:54 INFO - Running setup.py install for blobuploader
06:58:54 INFO - Running setup.py install for requests
06:58:56 INFO - Running setup.py install for docopt
06:58:56 INFO - Successfully installed blobuploader requests docopt
06:58:56 INFO - Cleaning up...
06:58:57 INFO - Return code: 0
06:58:57 INFO - Installing None into virtualenv C:\slave\test\build/venv
06: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')]}
06:58:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
06: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')]}
06:58:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
06:58:57 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 0x01791640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01835E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0173B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01842E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01904520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-107', 'USERDOMAIN': 'T-W732-IX-107', '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-107', '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
06:58:57 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
06:58:57 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
06:58:57 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
06:58:57 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
06:58:57 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
06:58:57 INFO - 'COMPUTERNAME': 'T-W732-IX-107',
06:58:57 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
06:58:57 INFO - 'CYGWINBASE': 'C:\\cygwin',
06:58:57 INFO - 'DCLOCATION': 'SCL3',
06:58:57 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
06:58:57 INFO - 'FP_NO_HOST_CHECK': 'NO',
06:58:57 INFO - 'HOMEDRIVE': 'C:',
06:58:57 INFO - 'HOMEPATH': '\\Users\\cltbld',
06:58:57 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
06:58:57 INFO - 'KTS_VERSION': '1.19c',
06:58:57 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
06:58:57 INFO - 'LOGONSERVER': '\\\\T-W732-IX-107',
06:58:57 INFO - 'MONDIR': 'C:\\Monitor_config\\',
06:58:57 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
06:58:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
06:58:57 INFO - 'MOZ_NO_REMOTE': '1',
06:58:57 INFO - 'NO_EM_RESTART': '1',
06:58:57 INFO - 'NUMBER_OF_PROCESSORS': '8',
06:58:57 INFO - 'OS': 'Windows_NT',
06:58:57 INFO - 'OURDRIVE': 'C:',
06:58: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;',
06:58:57 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
06:58:57 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
06:58:57 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
06:58:57 INFO - 'PROCESSOR_LEVEL': '6',
06:58:57 INFO - 'PROCESSOR_REVISION': '1e05',
06:58:57 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
06:58:57 INFO - 'PROGRAMFILES': 'C:\\Program Files',
06:58:57 INFO - 'PROMPT': '$P$G',
06:58:57 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
06:58:57 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
06:58:57 INFO - 'PUBLIC': 'C:\\Users\\Public',
06:58:57 INFO - 'PWD': 'C:\\slave\\test',
06:58:57 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
06:58:57 INFO - 'SLAVEDIR': 'C:\\slave\\',
06:58:57 INFO - 'SYSTEMDRIVE': 'C:',
06:58:57 INFO - 'SYSTEMROOT': 'C:\\windows',
06:58:57 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:58:57 INFO - 'TEST1': 'testie',
06:58:57 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:58:57 INFO - 'USERDOMAIN': 'T-W732-IX-107',
06:58:57 INFO - 'USERNAME': 'cltbld',
06:58:57 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
06:58:57 INFO - 'WINDIR': 'C:\\windows',
06:58:57 INFO - 'WINDOWS_TRACING_FLAGS': '3',
06:58:57 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
06:58:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
06:58:57 INFO - Ignoring indexes: https://pypi.python.org/simple/
06:58:57 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
06:58:57 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-j91c7z-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
06:58:57 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
06:58:57 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-_ujol6-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
06:58:57 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
06:58:57 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-w7vxei-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
06:58:58 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
06:58:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-1_b4et-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
06:58:58 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
06:58:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-84ejvv-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
06:58:58 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
06:58:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-f2v_sc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
06:58:58 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
06:58:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jjjd5g-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
06:58:58 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
06:58:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lwmv2v-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
06:58:58 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
06:58:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zdwwn8-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
06:58:58 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
06:58:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fgamra-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
06:58:59 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
06:58:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jribn4-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
06:58:59 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
06:58:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-exggju-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
06:58:59 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
06:58:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ewvuj_-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
06:58:59 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
06:58:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pwxbiz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
06:59:00 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
06:59:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ueyxni-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
06:59:00 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
06:59:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dufseh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
06:59:00 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
06:59:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-k1chak-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
06:59:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
06:59:00 INFO - Running setup.py install for manifestparser
06:59:00 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts
06:59:00 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts
06:59:01 INFO - Running setup.py install for mozcrash
06:59:01 INFO - Running setup.py install for mozdebug
06:59:01 INFO - Running setup.py install for mozdevice
06:59:02 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts
06:59:02 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts
06:59:02 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts
06:59:02 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts
06:59:02 INFO - Running setup.py install for mozfile
06:59:02 INFO - Running setup.py install for mozhttpd
06:59:03 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts
06:59:03 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts
06:59:03 INFO - Running setup.py install for mozinfo
06:59:03 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts
06:59:03 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts
06:59:03 INFO - Running setup.py install for mozInstall
06:59:03 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts
06:59:03 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts
06:59:03 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts
06:59:03 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts
06:59:03 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts
06:59:03 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts
06:59:03 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts
06:59:03 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts
06:59:04 INFO - Running setup.py install for mozleak
06:59:04 INFO - Running setup.py install for mozlog
06:59:04 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts
06:59:04 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts
06:59:05 INFO - Running setup.py install for moznetwork
06:59:05 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts
06:59:05 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts
06:59:05 INFO - Running setup.py install for mozprocess
06:59:06 INFO - Running setup.py install for mozprofile
06:59:06 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts
06:59:06 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts
06:59:06 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts
06:59:06 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts
06:59:06 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts
06:59:06 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts
06:59:06 INFO - Running setup.py install for mozrunner
06:59:07 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts
06:59:07 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts
06:59:07 INFO - Running setup.py install for mozscreenshot
06:59:07 INFO - Running setup.py install for moztest
06:59:08 INFO - Running setup.py install for mozversion
06:59:08 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts
06:59:08 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts
06:59:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion
06:59:08 INFO - Cleaning up...
06:59:08 INFO - Return code: 0
06:59:08 INFO - Installing None into virtualenv C:\slave\test\build/venv
06:59:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
06:59:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
06:59:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
06:59:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
06:59:08 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 0x01791640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01835E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0173B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01842E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01904520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-107', 'USERDOMAIN': 'T-W732-IX-107', '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-107', '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
06:59:08 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
06:59:08 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
06:59:08 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
06:59:08 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
06:59:08 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
06:59:08 INFO - 'COMPUTERNAME': 'T-W732-IX-107',
06:59:08 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
06:59:08 INFO - 'CYGWINBASE': 'C:\\cygwin',
06:59:08 INFO - 'DCLOCATION': 'SCL3',
06:59:08 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
06:59:08 INFO - 'FP_NO_HOST_CHECK': 'NO',
06:59:08 INFO - 'HOMEDRIVE': 'C:',
06:59:08 INFO - 'HOMEPATH': '\\Users\\cltbld',
06:59:08 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
06:59:08 INFO - 'KTS_VERSION': '1.19c',
06:59:08 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
06:59:08 INFO - 'LOGONSERVER': '\\\\T-W732-IX-107',
06:59:08 INFO - 'MONDIR': 'C:\\Monitor_config\\',
06:59:08 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
06:59:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
06:59:08 INFO - 'MOZ_NO_REMOTE': '1',
06:59:08 INFO - 'NO_EM_RESTART': '1',
06:59:08 INFO - 'NUMBER_OF_PROCESSORS': '8',
06:59:08 INFO - 'OS': 'Windows_NT',
06:59:08 INFO - 'OURDRIVE': 'C:',
06:59:08 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
06:59:08 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
06:59:08 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
06:59:08 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
06:59:08 INFO - 'PROCESSOR_LEVEL': '6',
06:59:08 INFO - 'PROCESSOR_REVISION': '1e05',
06:59:08 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
06:59:08 INFO - 'PROGRAMFILES': 'C:\\Program Files',
06:59:08 INFO - 'PROMPT': '$P$G',
06:59:08 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
06:59:08 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
06:59:08 INFO - 'PUBLIC': 'C:\\Users\\Public',
06:59:08 INFO - 'PWD': 'C:\\slave\\test',
06:59:08 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
06:59:08 INFO - 'SLAVEDIR': 'C:\\slave\\',
06:59:08 INFO - 'SYSTEMDRIVE': 'C:',
06:59:08 INFO - 'SYSTEMROOT': 'C:\\windows',
06:59:08 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:59:08 INFO - 'TEST1': 'testie',
06:59:08 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:59:08 INFO - 'USERDOMAIN': 'T-W732-IX-107',
06:59:08 INFO - 'USERNAME': 'cltbld',
06:59:08 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
06:59:08 INFO - 'WINDIR': 'C:\\windows',
06:59:08 INFO - 'WINDOWS_TRACING_FLAGS': '3',
06:59:08 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
06:59:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
06:59:08 INFO - Ignoring indexes: https://pypi.python.org/simple/
06:59:08 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
06:59:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-7syjf8-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
06:59:09 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))
06:59:09 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
06:59:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-k2cgnq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
06:59:09 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))
06:59:09 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
06:59:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-4jeyad-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
06:59:09 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))
06:59:09 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
06:59:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-2gcwgp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
06:59:09 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))
06:59:09 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
06:59:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ijyncn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
06:59:09 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))
06:59:09 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
06:59:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-tut59w-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
06:59:09 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))
06:59:09 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
06:59:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-l0cng4-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
06:59:09 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))
06:59:09 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
06:59:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zohb8n-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
06:59:10 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))
06:59:10 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
06:59:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-i3m5xn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
06:59:10 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))
06:59:10 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
06:59:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-6j9bme-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
06:59:10 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))
06:59:10 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
06:59:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xzrclo-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
06:59:10 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))
06:59:10 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
06:59:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-b_ci6o-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
06:59:10 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))
06:59:10 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
06:59:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-le5pn1-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
06:59:10 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))
06:59:10 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
06:59:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zw7min-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
06:59:10 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))
06:59:10 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
06:59:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hfmw5h-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
06:59:11 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))
06:59:11 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
06:59:11 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ngzb7j-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
06:59:11 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))
06:59:11 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
06:59:11 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cmzwjg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
06:59:11 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))
06:59:11 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))
06:59:11 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))
06:59:11 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))
06:59:11 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))
06:59:11 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))
06:59:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10))
06:59:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
06:59:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
06:59:12 INFO - Downloading blessings-1.6.tar.gz
06:59:12 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings
06:59:12 INFO - Installing collected packages: blessings
06:59:12 INFO - Running setup.py install for blessings
06:59:12 INFO - Successfully installed blessings
06:59:12 INFO - Cleaning up...
06:59:15 INFO - Return code: 0
06:59:15 INFO - Done creating virtualenv C:\slave\test\build/venv.
06:59:15 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
06:59:15 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
06:59:15 INFO - Reading from file tmpfile_stdout
06:59:15 INFO - Using _rmtree_windows ...
06:59:15 INFO - Using _rmtree_windows ...
06:59:15 INFO - Current package versions:
06:59:15 INFO - blessings == 1.6
06:59:15 INFO - blobuploader == 1.2.4
06:59:15 INFO - docopt == 0.6.1
06:59:15 INFO - functools32 == 3.2.3-2
06:59:15 INFO - jsonschema == 2.5.1
06:59:15 INFO - manifestparser == 1.1
06:59:15 INFO - mozInstall == 1.12
06:59:15 INFO - mozcrash == 0.17
06:59:15 INFO - mozdebug == 0.1
06:59:15 INFO - mozdevice == 0.48
06:59:15 INFO - mozfile == 1.2
06:59:15 INFO - mozhttpd == 0.7
06:59:15 INFO - mozinfo == 0.9
06:59:15 INFO - mozleak == 0.1
06:59:15 INFO - mozlog == 3.4
06:59:15 INFO - moznetwork == 0.27
06:59:15 INFO - mozprocess == 0.23
06:59:15 INFO - mozprofile == 0.28
06:59:15 INFO - mozrunner == 6.12
06:59:15 INFO - mozscreenshot == 0.1
06:59:15 INFO - mozsystemmonitor == 0.3
06:59:15 INFO - moztest == 0.7
06:59:15 INFO - mozversion == 1.4
06:59:15 INFO - psutil == 3.1.1
06:59:15 INFO - requests == 1.2.3
06:59:15 INFO - Installing None into virtualenv C:\slave\test\build/venv
06:59:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
06:59:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
06:59:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
06:59:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
06:59:15 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 0x01791640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01835E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0173B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01842E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01904520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-107', 'USERDOMAIN': 'T-W732-IX-107', '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-107', '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
06:59:16 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
06:59:16 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
06:59:16 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
06:59:16 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
06:59:16 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
06:59:16 INFO - 'COMPUTERNAME': 'T-W732-IX-107',
06:59:16 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
06:59:16 INFO - 'CYGWINBASE': 'C:\\cygwin',
06:59:16 INFO - 'DCLOCATION': 'SCL3',
06:59:16 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
06:59:16 INFO - 'FP_NO_HOST_CHECK': 'NO',
06:59:16 INFO - 'HOMEDRIVE': 'C:',
06:59:16 INFO - 'HOMEPATH': '\\Users\\cltbld',
06:59:16 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
06:59:16 INFO - 'KTS_VERSION': '1.19c',
06:59:16 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
06:59:16 INFO - 'LOGONSERVER': '\\\\T-W732-IX-107',
06:59:16 INFO - 'MONDIR': 'C:\\Monitor_config\\',
06:59:16 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
06:59:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
06:59:16 INFO - 'MOZ_NO_REMOTE': '1',
06:59:16 INFO - 'NO_EM_RESTART': '1',
06:59:16 INFO - 'NUMBER_OF_PROCESSORS': '8',
06:59:16 INFO - 'OS': 'Windows_NT',
06:59:16 INFO - 'OURDRIVE': 'C:',
06:59: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;',
06:59:16 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
06:59:16 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
06:59:16 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
06:59:16 INFO - 'PROCESSOR_LEVEL': '6',
06:59:16 INFO - 'PROCESSOR_REVISION': '1e05',
06:59:16 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
06:59:16 INFO - 'PROGRAMFILES': 'C:\\Program Files',
06:59:16 INFO - 'PROMPT': '$P$G',
06:59:16 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
06:59:16 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
06:59:16 INFO - 'PUBLIC': 'C:\\Users\\Public',
06:59:16 INFO - 'PWD': 'C:\\slave\\test',
06:59:16 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
06:59:16 INFO - 'SLAVEDIR': 'C:\\slave\\',
06:59:16 INFO - 'SYSTEMDRIVE': 'C:',
06:59:16 INFO - 'SYSTEMROOT': 'C:\\windows',
06:59:16 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:59:16 INFO - 'TEST1': 'testie',
06:59:16 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:59:16 INFO - 'USERDOMAIN': 'T-W732-IX-107',
06:59:16 INFO - 'USERNAME': 'cltbld',
06:59:16 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
06:59:16 INFO - 'WINDIR': 'C:\\windows',
06:59:16 INFO - 'WINDOWS_TRACING_FLAGS': '3',
06:59:16 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
06:59:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
06:59:16 INFO - Ignoring indexes: https://pypi.python.org/simple/
06:59:16 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))
06:59:16 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))
06:59:16 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))
06:59:16 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))
06:59:16 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))
06:59:16 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))
06:59:16 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))
06:59:16 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))
06:59:16 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))
06:59:16 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))
06:59:16 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))
06:59:16 INFO - Cleaning up...
06:59:16 INFO - Return code: 0
06:59:16 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv
06:59: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')]}
06:59:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
06:59: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')]}
06:59:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
06:59: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01791640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01835E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0173B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01842E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01904520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-107', 'USERDOMAIN': 'T-W732-IX-107', '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-107', '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
06:59: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', 'jsonschema'] in C:\slave\test\build
06:59: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 jsonschema
06:59:16 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
06:59:16 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
06:59:16 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
06:59:16 INFO - 'COMPUTERNAME': 'T-W732-IX-107',
06:59:16 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
06:59:16 INFO - 'CYGWINBASE': 'C:\\cygwin',
06:59:16 INFO - 'DCLOCATION': 'SCL3',
06:59:16 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
06:59:16 INFO - 'FP_NO_HOST_CHECK': 'NO',
06:59:16 INFO - 'HOMEDRIVE': 'C:',
06:59:16 INFO - 'HOMEPATH': '\\Users\\cltbld',
06:59:16 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
06:59:16 INFO - 'KTS_VERSION': '1.19c',
06:59:16 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
06:59:16 INFO - 'LOGONSERVER': '\\\\T-W732-IX-107',
06:59:16 INFO - 'MONDIR': 'C:\\Monitor_config\\',
06:59:16 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
06:59:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
06:59:16 INFO - 'MOZ_NO_REMOTE': '1',
06:59:16 INFO - 'NO_EM_RESTART': '1',
06:59:16 INFO - 'NUMBER_OF_PROCESSORS': '8',
06:59:16 INFO - 'OS': 'Windows_NT',
06:59:16 INFO - 'OURDRIVE': 'C:',
06:59: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;',
06:59:16 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
06:59:16 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
06:59:16 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
06:59:16 INFO - 'PROCESSOR_LEVEL': '6',
06:59:16 INFO - 'PROCESSOR_REVISION': '1e05',
06:59:16 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
06:59:16 INFO - 'PROGRAMFILES': 'C:\\Program Files',
06:59:16 INFO - 'PROMPT': '$P$G',
06:59:16 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
06:59:16 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
06:59:16 INFO - 'PUBLIC': 'C:\\Users\\Public',
06:59:16 INFO - 'PWD': 'C:\\slave\\test',
06:59:16 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
06:59:16 INFO - 'SLAVEDIR': 'C:\\slave\\',
06:59:16 INFO - 'SYSTEMDRIVE': 'C:',
06:59:16 INFO - 'SYSTEMROOT': 'C:\\windows',
06:59:16 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:59:16 INFO - 'TEST1': 'testie',
06:59:16 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:59:16 INFO - 'USERDOMAIN': 'T-W732-IX-107',
06:59:16 INFO - 'USERNAME': 'cltbld',
06:59:16 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
06:59:16 INFO - 'WINDIR': 'C:\\windows',
06:59:16 INFO - 'WINDOWS_TRACING_FLAGS': '3',
06:59:16 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
06:59:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
06:59:16 INFO - Ignoring indexes: https://pypi.python.org/simple/
06:59:16 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages
06:59:16 INFO - Cleaning up...
06:59:16 INFO - Return code: 0
06:59:16 INFO - Running post-action listener: _resource_record_post_action
06:59:16 INFO - Running post-action listener: _start_resource_monitoring
06:59:16 INFO - Starting resource monitoring.
06:59:16 INFO - [mozharness: 2016-10-18 13:59:16.854000Z] Finished create-virtualenv step (success)
06:59:16 INFO - [mozharness: 2016-10-18 13:59:16.854000Z] Running install step.
06:59:16 INFO - Running pre-action listener: _resource_record_pre_action
06:59:16 INFO - Running main action method: install
06:59:16 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
06:59:16 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
06:59:17 INFO - Reading from file tmpfile_stdout
06:59:17 INFO - Using _rmtree_windows ...
06:59:17 INFO - Using _rmtree_windows ...
06:59:17 INFO - Detecting whether we're running mozinstall >=1.0...
06:59:17 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h']
06:59:17 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h
06:59:17 INFO - Reading from file tmpfile_stdout
06:59:17 INFO - Output received:
06:59:17 INFO - Usage: mozinstall-script.py [options] installer
06:59:17 INFO - Options:
06:59:17 INFO - -h, --help show this help message and exit
06:59:17 INFO - -d DEST, --destination=DEST
06:59:17 INFO - Directory to install application into. [default:
06:59:17 INFO - "C:\slave\test"]
06:59:17 INFO - --app=APP Application being installed. [default: firefox]
06:59:17 INFO - Using _rmtree_windows ...
06:59:17 INFO - Using _rmtree_windows ...
06:59:17 INFO - mkdir: C:\slave\test\build\application
06:59:17 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']
06:59:17 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
06:59:20 INFO - Reading from file tmpfile_stdout
06:59:20 INFO - Output received:
06:59:20 INFO - C:\slave\test\build\application\firefox\firefox.exe
06:59:20 INFO - Using _rmtree_windows ...
06:59:20 INFO - Using _rmtree_windows ...
06:59:20 INFO - Running post-action listener: _resource_record_post_action
06:59:20 INFO - [mozharness: 2016-10-18 13:59:20.440000Z] Finished install step (success)
06:59:20 INFO - [mozharness: 2016-10-18 13:59:20.440000Z] Running run-tests step.
06:59:20 INFO - Running pre-action listener: _resource_record_pre_action
06:59:20 INFO - Running main action method: run_tests
06:59:20 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version']
06:59:20 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version
06:59:20 INFO - Python 2.7.3
06:59:20 INFO - Return code: 0
06:59:20 INFO - grabbing minidump binary from tooltool
06:59:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
06:59:20 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 0x01842E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01904520>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1
06:59:20 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
06:59:20 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
06:59:20 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
06:59:20 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'...
06:59:21 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmpkvfpzt
06:59:21 INFO - INFO - File integrity verified, renaming tmpkvfpzt to win32-minidump_stackwalk.exe
06:59:21 INFO - Return code: 0
06:59:21 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755
06:59:21 INFO - mkdir: C:\slave\test\build\blobber_upload_dir
06:59:21 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir
06:59:21 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe
06:59:21 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir
06:59:21 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/1476786616/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-107', '--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
06:59:21 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/1476786616/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-107 --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
06:59:21 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
06:59:21 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
06:59:21 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
06:59:21 INFO - 'COMPUTERNAME': 'T-W732-IX-107',
06:59:21 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
06:59:21 INFO - 'CYGWINBASE': 'C:\\cygwin',
06:59:21 INFO - 'DCLOCATION': 'SCL3',
06:59:21 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
06:59:21 INFO - 'FP_NO_HOST_CHECK': 'NO',
06:59:21 INFO - 'HOMEDRIVE': 'C:',
06:59:21 INFO - 'HOMEPATH': '\\Users\\cltbld',
06:59:21 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
06:59:21 INFO - 'KTS_VERSION': '1.19c',
06:59:21 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
06:59:21 INFO - 'LOGONSERVER': '\\\\T-W732-IX-107',
06:59:21 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir',
06:59:21 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe',
06:59:21 INFO - 'MONDIR': 'C:\\Monitor_config\\',
06:59:21 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
06:59:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
06:59:21 INFO - 'MOZ_NO_REMOTE': '1',
06:59:21 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir',
06:59:21 INFO - 'NO_EM_RESTART': '1',
06:59:21 INFO - 'NUMBER_OF_PROCESSORS': '8',
06:59:21 INFO - 'OS': 'Windows_NT',
06:59:21 INFO - 'OURDRIVE': 'C:',
06:59: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;',
06:59:21 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
06:59:21 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
06:59:21 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
06:59:21 INFO - 'PROCESSOR_LEVEL': '6',
06:59:21 INFO - 'PROCESSOR_REVISION': '1e05',
06:59:21 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
06:59:21 INFO - 'PROGRAMFILES': 'C:\\Program Files',
06:59:21 INFO - 'PROMPT': '$P$G',
06:59:21 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
06:59:21 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
06:59:21 INFO - 'PUBLIC': 'C:\\Users\\Public',
06:59:21 INFO - 'PWD': 'C:\\slave\\test',
06:59:21 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos',
06:59:21 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
06:59:21 INFO - 'SLAVEDIR': 'C:\\slave\\',
06:59:21 INFO - 'SYSTEMDRIVE': 'C:',
06:59:21 INFO - 'SYSTEMROOT': 'C:\\windows',
06:59:21 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:59:21 INFO - 'TEST1': 'testie',
06:59:21 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:59:21 INFO - 'USERDOMAIN': 'T-W732-IX-107',
06:59:21 INFO - 'USERNAME': 'cltbld',
06:59:21 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
06:59:21 INFO - 'WINDIR': 'C:\\windows',
06:59:21 INFO - 'WINDOWS_TRACING_FLAGS': '3',
06:59:21 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
06:59:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
06:59:21 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/1476786616/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-107', '--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
06:59:22 INFO - mozversion application_buildid: 20161018033016
06:59:22 INFO - mozversion application_changeset: dc89484d4b45abf442162e5ea2dd46f9de40197d
06:59:22 INFO - mozversion application_display_name: Nightly
06:59:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
06:59:22 INFO - mozversion application_name: Firefox
06:59:22 INFO - mozversion application_remotingname: firefox
06:59:22 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team
06:59:22 INFO - mozversion application_vendor: Mozilla
06:59:22 INFO - mozversion application_version: 52.0a1
06:59:22 INFO - mozversion platform_buildid: 20161018033016
06:59:22 INFO - mozversion platform_changeset: dc89484d4b45abf442162e5ea2dd46f9de40197d
06:59:22 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team
06:59:22 INFO - mozversion platform_version: 52.0a1
06:59:22 INFO - using testdate: 1476799162
06:59:22 INFO - actual date: 1476799162
06:59:22 INFO - starting webserver on 'localhost:49752'
06:59:22 INFO - SUITE-START | Running 2 tests
06:59:22 INFO - TEST-START | dromaeo_css
06:59:22 INFO - operating with platform_type : w7_
06:59:22 INFO - Initialising browser for dromaeo_css test...
06:59:22 INFO - TEST-INFO | started process 1620 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpb5owi7\profile http://localhost:49752/getInfo.html)
06:59:29 INFO - PROCESS | 1620 | __metrics Screen width/height:1600/1200
06:59:29 INFO - PROCESS | 1620 | colorDepth:24
06:59:29 INFO - PROCESS | 1620 | Browser inner width/height: 1010/674
06:59:29 INFO - PROCESS | 1620 | __metrics
06:59:30 INFO - PROCESS | 1620 | 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"
06:59:30 INFO - PROCESS | 1620 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
06:59:31 INFO - TEST-INFO | 1620: exit 0
06:59:31 INFO - Browser initialized.
06:59:31 INFO - Running cycle 1/1 for dromaeo_css test...
06:59:31 INFO - TEST-INFO | started process 944 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpb5owi7\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1)
06:59:32 INFO - PROCESS | 944 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0
06:59:43 INFO - PROCESS | 944 | RSS: Main: 123015168
06:59:43 INFO - PROCESS | 944 |
07:00:34 INFO - PROCESS | 944 | 1476799234644 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]
07:02:45 INFO - PROCESS | 944 | Cycle 1(1): loaded http://localhost:49752/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49752/tests/dromaeo/cssquery-ext.html)
07:02:47 INFO - PROCESS | 944 | RSS: Main: 275673088
07:02:47 INFO - PROCESS | 944 |
07:05:49 INFO - PROCESS | 944 | Cycle 1(1): loaded http://localhost:49752/tests/dromaeo/cssquery-ext.html (next: http://localhost:49752/tests/dromaeo/cssquery-jquery.html)
07:05:50 INFO - PROCESS | 944 | RSS: Main: 197828608
07:05:50 INFO - PROCESS | 944 |
07:08:52 INFO - PROCESS | 944 | Cycle 1(1): loaded http://localhost:49752/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49752/tests/dromaeo/cssquery-mootools.html)
07:08:52 INFO - PROCESS | 944 | RSS: Main: 140845056
07:08:52 INFO - PROCESS | 944 |
07:11:54 INFO - PROCESS | 944 | Cycle 1(1): loaded http://localhost:49752/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49752/tests/dromaeo/cssquery-prototype.html)
07:11:55 INFO - PROCESS | 944 | RSS: Main: 140591104
07:11:55 INFO - PROCESS | 944 |
07:14:58 INFO - PROCESS | 944 | Cycle 1(1): loaded http://localhost:49752/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49752/tests/dromaeo/cssquery-yui.html)
07:14:58 INFO - PROCESS | 944 | RSS: Main: 132747264
07:14:58 INFO - PROCESS | 944 |
07:18:01 INFO - PROCESS | 944 | Cycle 1(1): loaded http://localhost:49752/tests/dromaeo/cssquery-yui.html (next: http://localhost:49752/tests/dromaeo/cssquery-dojo.html)
07:18:01 INFO - PROCESS | 944 | RSS: Main: 181706752
07:18:01 INFO - PROCESS | 944 |
07:18:01 INFO - PROCESS | 944 | __start_tp_report
07:18:01 INFO - PROCESS | 944 | _x_x_mozilla_page_load
07:18:01 INFO - PROCESS | 944 | _x_x_mozilla_page_load_details
07:18:01 INFO - PROCESS | 944 | |i|pagename|runs|
07:18:01 INFO - PROCESS | 944 | |0;dojo.html;2751.8624068796557;2771.4734200501916;2775.7224277572245;2781.374190807068;2785.2897511134656;4729.550692684198;4740.217864052436;4746.952530474694;4825.90348193036;4828.927566086507;4717.716936983783;4724.6456515761365;4728.90542189156;4736.502667219944;4858.757062146888;4206.831726730927;4214.831406743727;4241.4698162729655;4249.043965107841;4257.318828987362;4052.8986775330704;4165.229432554972;4168.895777605569;4232.788360581974;4257.297545904938;4172.895677608054;4177.12280421112;4277.443932288814;4291.249031419505;4293.0340673348555;4113.362428823537;4145.66834653227;4173.227952828736;4179.1432756284885;4269.935950960738;4121.361189015707;4183.748975061506;4201.7268877523165;4205.66354691627;4215.957840421587;4656;4710.481846996828;4750.714957102585;4761.999980004203;4762.547557981986;4800.4719480857075;4934.062528119682;4941.085899108676;4950.8267210647455;5040.218766091261;4991.575716064139;5013.248012798073;5029.270755740434;5057.696538207682;5112.718800465973;4554.27131658933;4625.074985003012;4661.021185551004;4719.3392924990185;4742.5731684148595;4938.086454005948;5099.4390617032095;5194.11700010999;5206.3752349717515;5219.373675159005;4822.469528351879;4859.1010663027455;4868.245421959602;4886.096072226649;4941.407031156285;5005.949940500548;5016.749162541858;5026.396832380138;5027.84916452507;5032.823851165192;4938.802447902115;4961.08219979305;4989.326440930428;5001.924971125436;5013.523715247045;4865.756712164378;4891.926621100686;4960.479149689303;4984.875378115504;5013.573846223039;3390.271091715293;3400.25194457219;3402.353552825005;3405.2678674084696;3410.693037626576;2988.1334413020413;3063.3107550801337;3089.9691003089683;3101.7673674474504;3121.157287532399;4625.5836974671765;4715.504871988392;4716.952830471651;4775.856724298276;4786.473487916326;4892.19278818998;4943.332650092263;4973.726445045557;5057.115004874206;5113.619322782828;4513.81944722214;4561.338605902126;4659.44086709597;4680.695754775928;4768.117898188846;3916.9020774480296;3954.4859168307935;4020.3768415895584;4062.9639441942118;4157;5070.315507406527;5177.974110129425;5318.787248510093;5321.494458026481;5322.707251101227;4829.468758436639;4979.452260251442;5000.570614825081;5096.949030509796;5118.97440512795;4999.875003124951;5042.873928151679;5252.501012403815;5261.947380526146;5265.763040663256;4944.258361245842;4959.876003099951;4961.454240033521;4969.428515720739;4981.028699403581;5053.469385714447;5057.443681195139;5064.392272927273;5064.924026139537;5074.416442109057;4725;4863.732494712684;4886.071646387175;4908.656394052382;4909.214525675875;4628.375169352094;4867.221244600847;4872.805087796591;4946.35697359341;4957.132501812241;4826.855194344175;4861.635377346643;4863.562279394871;4866.829660961849;4877.365942427604;4748.216544270157;4833.854984350475;4882.609391248762;4958.330625365674;4972.9254061188385;4818.4217893852965;4826.3243145958895;4914.262860570943;4917.434495033117;4934.062528119646
07:18:01 INFO - PROCESS | 944 | |1;ext.html;535.7883635963794;549.4395716369304;552.4834279948249;557.8214971209212;558.0708120978571;26636.067737629186;28246.85876570617;29736.25659358517;29802.850985745095;29809.552856707167;27761.722382776166;29547;38350.4247436288;39046.80476597613;40527.59472405275;7828.725994590176;8370.372222083368;8400.705975290866;8405.075441701409;8419.70531031414;8137.633806478722;8222.17778222179;8237.258646721793;8243.587820608946;8248.83502329953;6740.427063699592;6932.098827152463;8224.300934420582;10002.34984725994;10455.634052808153;10118.949405252963;10213.416920377316;10216.029477199729;10242.795144097076;10258.846117308247;9356.391834530801;9419.670311539063;9779.657711980046;9862.260330475243;10039.799204015879;35741.28517429637;37168.44247336292;37596.24807503835;37604;37682.24635507302;2950.2329394357407;2974.7471464925507;3029.560713696502;3030.7423868971164;3261.266215101607;29128.417431651247;29634.259143521584;29673.70326296731;29886;30406;3406.744494162948;3518.48981897626;3523.2953409317984;3571.607123216444;3576.8211589420685;296256;300411.49794251105;300761;301483;302267.4886625553;48175.75912120487;49366;50588;50626.74686626617;50659.147832086084;28432.14703558855;29045.56431653527;51825.740871296155;53087.46912530903;53171.46828531742;11577.363245021435;12238.632841014783;12369.443375047964;12410.131290809557;12427.689307767198;9812.558434870449;9866.851997220047;9867.901320986843;9869.309148359689;9870.25973052024;16557.172141393123;16603.6679266414;16625.6674866502;16633.50099497017;16664.16679166061;5216.478352164753;6451.774187903401;6456.451201647818;6520.706568204442;6543.280239173687;734.1316349889544;762.0169980724913;810.8864758933754;867.4535042922918;945.5414124149781;26687.33281667974;27057.86471067632;27517.31206719808;27558.448831023266;27681.169564913085;935.2331088507494;956.3018996132862;970.5680971967548;973.0221127766691;1001.3491230700104;14155.29223538819;14919.477818276526;15220;15245.466408675644;15541.145237012073;481425;481609.7182993032;481898;486948;489708;46932;47630.52369476261;47663.76168119137;47675;47732;994.9303548751662;1019.2559431614815;1060.8249638809818;1126.6676330482528;1129.0459561670375;25597.72997492194;27989.16407902152;28009.299767505974;28139.859300703367;28177;40338.39492407675;41491.37762933619;42112.789436052626;42204.36693449662;42212.577874222094;367915;369213;370100;370707;371413;22954.311370658907;38648;39580.40629390619;39609.80195099006;39698;41323.80142978577;42220.36669449899;42278.36582451204;42985.7850710757;43196.56803432051;35786;35855.46216806802;36072;36399.27201456009;37936.24127517379;36562.8171859139;36951.81524092362;37055.62944370522;37112.25775484421;37352.75883447002;33073.173170670925;33846.83076584601;34991.475127873615;35094.29811403809;35145.82427087951;30718.078457646305;31982;32364.83817580897;33167.33665326728;33717.49423758694;24302.392440189138;24604.876975615007;25056.749432505443;25107.497850043263;25250.74749252484
07:18:01 INFO - PROCESS | 944 | |2;jquery.html;297.13089214048904;322.159164580445;323.92225865792204;325.97392208623296;326.5672983297132;12991.155574887638;13610.251436171018;13707.657308567292;13721.313934303294;13757.380917858696;13050.412731427108;13570.525031623894;13633.1138475999;15690.37238510461;16814;5202;5239.79040838366;5251.711155886424;5375.946240537603;5489.39616642169;5187.792488300463;5331.973340133294;5384.515393614574;5393.83818485446;5398.24424580559;5314.096603577382;5325.600579956499;5947.702614869239;6262.749490020394;6347.555671103025;6030.276366835964;6156.199694039746;6160.784372546984;6187.226596675416;6192.195014648112;5033.3205017322925;5183.844484665466;5278.894422111574;5299.152135658276;5421.864453388657;11077.224594278403;11339.433028348632;11442.656720298404;11500.712482188012;11510.712232194179;1751.5445984044113;1803.0263657624866;1855.7958624551288;1887.3488646416963;2022.1506967073865;9089.818203635956;9225.953870230673;9240.722778316662;9333.719988400358;9347.878254609423;1740.9825901740946;1760.471858442475;1790.9641807163785;1791.6506281275156;1857.6377606366764;81438.59280703618;82297;82951.17048829555;84475.5776221115;85505;9114.179723824887;9496.952515237379;9562.521873906277;9572.521373931275;9679.758006049906;12196.085293603015;12595.118341716176;12616.74766504683;12625.05312101596;12634.115611907262;6040;6937.791866244021;6964.119687684398;7048.718051277994;7054.541454805421;6105.389461053859;6114.816555503342;6569.638669873108;6869.690863911136;6900.309969003059;3932.724709270381;3942.9605703942593;4004.2391945530258;4011.1777085697363;4023.9597604023584;1635.4276003398716;1653.4626246469945;1686.5530634381898;1692.9746053809204;1707.7011522983428;763.6983391560302;824.282873899711;849.2823564088335;889.2441424789007;920.3189639666598;17464.73802892932;17628.647427051645;17844;17858.642827143645;18314.908425457787;773.5977291808229;787.5589669784939;790.3005839831675;804.047204063187;817.6320655704957;8213.589320533949;9929.553170107363;9961.203103751828;9964.551595178234;10049.095581397709;432584;438323;449246;451729;455476;99509.66916413825;104523;106073.46963265134;106119.4694026525;106883.46558267159;775.7013549783373;777.3043126401951;800.639712129544;801.1947992267734;801.587182600961;16201.918990405444;16433.260503277143;16656;16665.583360416087;16980.320787168373;43976.56023439852;44655.77672111749;44957.87605309893;45608;47392.52607473881;60850.39149608447;63115;63677;64636;66081;7317.26827317264;7471.17817040136;7537.321641052332;7589.696412143452;7702.499337543042;8716.041235463967;12105.515779368727;12502.749945001231;12817.487300507875;12860.099793014399;9903.801923961337;10315.845262320921;10502.360416708188;10843.891561084289;10934.890651093387;10595.894041059491;10658.573657053632;10670.35977841332;10807.729806754895;10950.06924411434;10409.68770936873;10965.396903170402;10977;11276.154288428565;11315.660530184108;7380.446466514925;7449.4412919032375;7652.38780897538;7685.654145563351;7762.146163921851;8503.659853605786;9869.210463163074;10341.276110672181;10351.275410721177;10909.127269818237
07:18:01 INFO - PROCESS | 944 | |3;mootools.html;1054.8417737339403;1123.4775829239402;1131.9320840749554;1227.7667243223789;1418.1703703333555;6449.290578036413;6766.289539598345;6855.828604284895;7140.428765698744;7360.190379058299;6912.58524488531;7053.964730176354;7305.488615796897;7336.449766267525;7364.042674452327;1915.0711904726231;1928.864979451439;1929.4308179087202;1943.815337542931;1945.3483083167157;1887.631911777204;1892.0539730134933;1905.8284754372103;1910.2263583248807;1912.4931893048354;1831.7435559021746;1895.848332133433;1921.9231230750754;1927.778305494872;1929.9228030878749;1804.7383129446223;1826.278619945121;1877.4930768692377;1890.3478299986482;1893.195391958426;1832.3220408448958;1856.860735444847;1858.4053103007043;1860.1908169945982;1864.2077117225126;6950.409215216712;7099.858002839966;7129.607871567062;7174.282571742836;7187.64061796908;4285.4214681017975;4313.374560688682;4323.135372925428;4385.495667998189;4387.714798538117;2999.0702882106616;3052.9389412211854;3053.1451193665807;3053.4503789317914;3059.5314249160306;916.4089162490224;921.1525396635112;924.246738907788;958.5063692198454;960.2174228004154;9184.63261469547;9316.627334906596;9320.161185493338;9322.580483878242;9339.159475647157;3596;3622.2936527377437;3690.726699288741;3692.907677308035;3693.593704692482;7293.635318234067;7337.853242935112;7346.926530734624;7349.375303099189;7354;4276.679249056333;4294.7852607369505;4314.56854314566;4320.4599425071865;4323.437953066082;3620.5655321361605;3643.125649844071;4088.509378874554;4095.672346212296;4104.035551645361;2865.4698880707138;2943.838088905131;2955.7192066753623;2994.9101526954228;3087.1356020314347;883.2448256740395;906.6690657909948;914.0996118822954;930.8976012638605;931.4132096778986;683.9829004274933;800.7437619961556;807.6446363599997;813.3858936502902;838.2037064788475;4707.270373092108;4837.032593481318;4895.4370247422;4917.131490033836;4981.950180498148;787.1066339704477;816.7059858450953;822.6421506644629;824.9051359093781;855.683397143061;3091.9536206956914;3167;3233.288676491168;3327.8169700666213;3364.444866597033;8160.836783264302;8493.660253589787;8513.10612385703;8514.829703405898;8546;8178.918210817816;8423.578821058922;8449.662013519513;8502.14978502145;8514.318854491627;6791.864162716817;6887.827804304832;6905.827354316082;6922.2731613180895;6924.723011079601;4737.076270127292;4782.95217047839;4807.735574543433;4837.7097374157665;4888.340074089952;7972.043354797462;8021.799455013671;8162.306203972491;8204.753857384288;8211.425200235926;8099.311558517592;8324.417290789586;8354.665813367641;8367.874481882898;8381.653137121615;5805.419458054161;5929.169916211647;5930.940690593211;5956.434138756811;5957.463828255478;6326.715297811517;6927.618980955895;6987.475939304471;7021.192562855337;7025.121859767529;5497.230387745799;5753.8561535961935;6060.636361818305;6193.4425901669065;6202.689865506616;6168.444839964424;6228.532860035606;6242.53181011435;6294.402031806971;6313.305536391085;5523.944760552504;5689.288838895138;5706.629069110494;5714.514266287412;5726.198332233574;3908.7459315144424;4094.8362065517044;4141.399497072968;4146.129312844337;4188.581141885909;4090.5909409058854;4195.97902010488;4198;4226.915461690811;4318.35224716295
07:18:01 INFO - PROCESS | 944 | |4;prototype.html;255.92706078767554;262.7215151938946;263.27861659054196;265.3459223015267;265.64271055430453;4718.693284936477;4905.460399356068;5061.873453163673;5134.589232861372;5155.587552995766;4663.27719203524;4968.826091086813;4974.950250497494;5012.548870601645;5041.571466425358;3722.8324725387424;3746.269477451898;3746.3256613809504;3746.3631182699005;3753.174301653632;3553.8400771965325;3653.104989277631;3678.209185025219;3752;3755.5493340799007;3594.4428613564937;3610.548681414823;3637.3089113068563;3675.375186218336;3690.3172913011044;3610.981945090258;3628.092976755811;3677.9954924566177;3680.742348035625;3685;3134.0911135770825;3151.101935948244;3198.74410047198;3208.5187221916667;3212.405704944593;4783.832565860213;4896.853094407174;4901.681390709592;4920.384951881015;4930.137225985474;3850.4416859555486;4195.538490766014;4245.766482843443;4320.71915325506;4322.848700295506;5186.948130518685;5187.403448603422;5216.921746173811;5235.921461178085;5250.579953603703;3268.3953468608383;3416.350893330259;3434.708049815769;3435.9312813743836;3440.6043305019994;5315.3090098286975;5364.946350536484;5420.642020720999;5449.264349312832;5456.781728730846;3400.880969166067;3524.5418095647915;3529.5940966788635;3532.9823350883594;3536.681698647134;3552.4138517144897;3559.94660080099;3565.857365705395;3566.625504322061;3578.785272883635;4199.391088292181;4214.093969796509;4220.198162349144;4221.514525829569;4235.385869048971;5239.454360963525;5241.453771137523;5244.344456942882;5249.737513124405;5270;1177.6996865799338;1193.5106606291379;1205.8552973643218;1209.9697507562382;1213.350857291345;793.8967934168521;807.7617102954642;808.2685169921231;808.458332916947;813.251808336332;4766.928496072562;4918.188498897642;4938.456769755324;4942.283368911488;4957.206846904478;5377.220303056035;5397;5398.595105367034;5404.216388623706;5411.404745477994;4819.156647586658;4920.803167873245;4931.5315045070665;4933.161362568372;4949.628777841676;2805.7474827265646;2840.531312333483;2849.002849002874;2855.628768260113;2867.2975121095465;5578.386377498472;5599.412061733459;5604.131359639263;5634.605577609528;5635.6618602883955;5466.808661696821;5478.150886612581;5506.614536982453;5510.669359838343;5531.170324451284;5287.788488460418;5646.153077038477;5719.913216488854;5757.424257574209;5769.365369809401;5462.634341414647;5499.532539734168;5502.642328248651;5518.420565840529;5531.751071201886;5403.4326395727885;5412.729363531729;5415.972920135532;5429.891402171856;5436.592255580927;5489.258950041853;5608.270924779753;5766.509846662912;5783.913241301299;5791.131330300489;3431.279431319451;3529.4705794131087;3537.4870643757026;3547.3614749345365;3559.7864128152396;5434.701091440009;5477.534409575072;5505.559555235492;5510.586705997146;5514.5864060194845;5320.414754377093;5322.680639161663;5337.146056630921;5347.679139251657;5353.839384818461;5084.3644544431945;5194.1429664105;5204.427512973494;5267.947320526899;5383.219433182088;4998.1503144464805;5205.557527610195;5207.583393328448;5233.790648374023;5246.344206974129;5098.490150984871;5189.195674670432;5236.423993360803;5336.893262134813;5348.304720386325;5241.973790131177;5262.210668399771;5263.447338029452;5265.473452654704;5268.262443258023
07:18:01 INFO - PROCESS | 944 | |5;yui.html;627.8963970944792;685.4653370371107;687.8280429892527;688.3632639808178;689.7275576147422;14085;15308.311125999342;15341.769873451893;15835.445759398423;16639.750403743954;14550.636234094152;14900.25498725062;15102.622434439145;15134.4702935397;15190.164540950243;612.6752821004864;621.505902807267;625.6121204852998;626.1453116495973;627.9466245369126;498.7755510020487;505.23204728812186;609.7500024989755;612.883552125097;633.3982716419397;642.5341627320203;654.6988385342748;657.0472814419088;657.6514447342915;658.1509852290563;510.7701534309538;646.3052218864716;652.4910569755598;658.9044588534659;659.96370199639;373.89530931339266;454.9408576884984;470.0786458541263;472.3481595398328;474.962003039756;12259;12305.44625491846;12333.753324933452;12391.132620716553;12399.504019839196;12324.75350492994;12624;13016.609501714964;13210.273434961073;13375.598732038054;13399.665008374772;13480.730385392237;13486.123401978939;13775.862241377557;14110.576682699433;4235.301175306071;4338.284183109783;4363.80362883671;4378.824847006116;4420.447444069491;76847.23152768456;82521.17478825255;82767.58616206881;82853.58573207156;83760.16239837585;10705.732356691145;10747.83878241827;10795.6221532246;10832.78334433307;10839.566417343218;19736.11187496566;19771.604567908562;19841;19842.1071051803;19857.106430210675;7443.292887175818;7463.253674632602;7546.5094768840945;7561.168271490132;7614.505057171266;3832.5017747693178;3842.5773164951834;3948.289307924601;4026.0941288210506;4043.7978101095414;2620.5414052540727;2871.9425611488073;2879.755220806213;2884.13475957212;2894.768418526513;500.95741861941417;511.9897602047938;521.5123859191668;529.8278059630559;533.6931264522915;626.8213559135625;649.5875119299185;653.8496145886472;654.456800855289;669.0967194287673;9213.12475314844;9252.259819214447;9321.254299656011;9575.952120239353;9621.663241786644;634.5684934244665;649.2598437780933;651.7425616881305;652.425865238587;660.4947215380238;4500.234960056799;4602.7468489233415;4614.377059097046;4664.626829853603;4757.524247575215;62739;65195.67402163053;68094.65952670205;76088;121544.39227803804;58310.416895831346;59607;59993.40006599878;60414.69792651009;70684;87185.12814871897;88111.35665930032;89177.55411223033;89575;91205.54397228104;9137.360384773268;9936.211481933322;9962.153216976349;10046;10059.245556583432;46829.76585117052;48931.266031008854;49392;49476;50449.747751261006;70871;77187.88242051941;77260.61369693115;77392.22607773851;77914.6104269475;10656.200784941006;10790.682934558918;10853.837192441963;10913.781724365626;11054.723631908944;14432.340280867835;14809.833370694078;14943.252837358306;14965.625859353604;15264.3894244229;13366.198028118344;13475.258860762751;13827.643374067857;14050.508232211823;14089.929550352183;12869.871301287241;13449.798253026016;13977.231252281223;14539.636509087357;14648.19434931089;13528.797068044185;13819.930900345435;13821.654458638615;14119.64700882486;14375.281235937951;11745.882541174478;12173.817392738938;12239.938800305941;12467.251964882136;12642.115051946275;11352;11971.461284242056;12041.939790300992;12074.818877717016;12116.515339386679
07:18:01 INFO - PROCESS | 944 | __end_tp_report
07:18:01 INFO - PROCESS | 944 | __start_cc_report
07:18:01 INFO - PROCESS | 944 | _x_x_mozilla_cycle_collect,2061
07:18:01 INFO - PROCESS | 944 | __end_cc_report
07:18:01 INFO - PROCESS | 944 | __startTimestamp1476800281752__endTimestamp
07:18:01 INFO - PROCESS | 944 |
07:18:01 INFO - PROCESS | 944 | ------- Summary: start -------
07:18:01 INFO - PROCESS | 944 | Number of tests: 6
07:18:01 INFO - PROCESS | 944 |
07:18:01 INFO - PROCESS | 944 | [#0] dojo.html Cycles:165 Average:4613.81 Median:4829.47 stddev:587.33 (12.2%) stddev-sans-first:570.68
07:18:01 INFO - PROCESS | 944 | Values: 2751.9 2771.5 2775.7 2781.4 2785.3 4729.6 4740.2 4747.0 4825.9 4828.9 4717.7 4724.6 4728.9 4736.5 4858.8 4206.8 4214.8 4241.5 4249.0 4257.3 4052.9 4165.2 4168.9 4232.8 4257.3 4172.9 4177.1 4277.4 4291.2 4293.0 4113.4 4145.7 4173.2 4179.1 4269.9 4121.4 4183.7 4201.7 4205.7 4216.0 4656.0 4710.5 4750.7 4762.0 4762.5 4800.5 4934.1 4941.1 4950.8 5040.2 4991.6 5013.2 5029.3 5057.7 5112.7 4554.3 4625.1 4661.0 4719.3 4742.6 4938.1 5099.4 5194.1 5206.4 5219.4 4822.5 4859.1 4868.2 4886.1 4941.4 5005.9 5016.7 5026.4 5027.8 5032.8 4938.8 4961.1 4989.3 5001.9 5013.5 4865.8 4891.9 4960.5 4984.9 5013.6 3390.3 3400.3 3402.4 3405.3 3410.7 2988.1 3063.3 3090.0 3101.8 3121.2 4625.6 4715.5 4717.0 4775.9 4786.5 4892.2 4943.3 4973.7 5057.1 5113.6 4513.8 4561.3 4659.4 4680.7 4768.1 3916.9 3954.5 4020.4 4063.0 4157.0 5070.3 5178.0 5318.8 5321.5 5322.7 4829.5 4979.5 5000.6 5096.9 5119.0 4999.9 5042.9 5252.5 5261.9 5265.8 4944.3 4959.9 4961.5 4969.4 4981.0 5053.5 5057.4 5064.4 5064.9 5074.4 4725.0 4863.7 4886.1 4908.7 4909.2 4628.4 4867.2 4872.8 4946.4 4957.1 4826.9 4861.6 4863.6 4866.8 4877.4 4748.2 4833.9 4882.6 4958.3 4972.9 4818.4 4826.3 4914.3 4917.4 4934.1
07:18:01 INFO - PROCESS | 944 |
07:18:01 INFO - PROCESS | 944 | [#1] ext.html Cycles:180 Average:52241.20 Median:203082.41 stddev:104043.69 (51.2%) stddev-sans-first:104263.13
07:18:01 INFO - PROCESS | 944 | Values: 535.8 549.4 552.5 557.8 558.1 26636.1 28246.9 29736.3 29802.9 29809.6 27761.7 29547.0 38350.4 39046.8 40527.6 7828.7 8370.4 8400.7 8405.1 8419.7 8137.6 8222.2 8237.3 8243.6 8248.8 6740.4 6932.1 8224.3 10002.3 10455.6 10118.9 10213.4 10216.0 10242.8 10258.8 9356.4 9419.7 9779.7 9862.3 10039.8 35741.3 37168.4 37596.2 37604.0 37682.2 2950.2 2974.7 3029.6 3030.7 3261.3 29128.4 29634.3 29673.7 29886.0 30406.0 3406.7 3518.5 3523.3 3571.6 3576.8 296256.0 300411.5 300761.0 301483.0 302267.5 48175.8 49366.0 50588.0 50626.7 50659.1 28432.1 29045.6 51825.7 53087.5 53171.5 11577.4 12238.6 12369.4 12410.1 12427.7 9812.6 9866.9 9867.9 9869.3 9870.3 16557.2 16603.7 16625.7 16633.5 16664.2 5216.5 6451.8 6456.5 6520.7 6543.3 734.1 762.0 810.9 867.5 945.5 26687.3 27057.9 27517.3 27558.4 27681.2 935.2 956.3 970.6 973.0 1001.3 14155.3 14919.5 15220.0 15245.5 15541.1 481425.0 481609.7 481898.0 486948.0 489708.0 46932.0 47630.5 47663.8 47675.0 47732.0 994.9 1019.3 1060.8 1126.7 1129.0 25597.7 27989.2 28009.3 28139.9 28177.0 40338.4 41491.4 42112.8 42204.4 42212.6 367915.0 369213.0 370100.0 370707.0 371413.0 22954.3 38648.0 39580.4 39609.8 39698.0 41323.8 42220.4 42278.4 42985.8 43196.6 35786.0 35855.5 36072.0 36399.3 37936.2 36562.8 36951.8 37055.6 37112.3 37352.8 33073.2 33846.8 34991.5 35094.3 35145.8 30718.1 31982.0 32364.8 33167.3 33717.5 24302.4 24604.9 25056.7 25107.5 25250.7
07:18:01 INFO - PROCESS | 944 |
07:18:01 INFO - PROCESS | 944 | [#2] jquery.html Cycles:180 Average:27210.98 Median:26212.92 stddev:74443.30 (284.0%) stddev-sans-first:74624.70
07:18:01 INFO - PROCESS | 944 | Values: 297.1 322.2 323.9 326.0 326.6 12991.2 13610.3 13707.7 13721.3 13757.4 13050.4 13570.5 13633.1 15690.4 16814.0 5202.0 5239.8 5251.7 5375.9 5489.4 5187.8 5332.0 5384.5 5393.8 5398.2 5314.1 5325.6 5947.7 6262.7 6347.6 6030.3 6156.2 6160.8 6187.2 6192.2 5033.3 5183.8 5278.9 5299.2 5421.9 11077.2 11339.4 11442.7 11500.7 11510.7 1751.5 1803.0 1855.8 1887.3 2022.2 9089.8 9226.0 9240.7 9333.7 9347.9 1741.0 1760.5 1791.0 1791.7 1857.6 81438.6 82297.0 82951.2 84475.6 85505.0 9114.2 9497.0 9562.5 9572.5 9679.8 12196.1 12595.1 12616.7 12625.1 12634.1 6040.0 6937.8 6964.1 7048.7 7054.5 6105.4 6114.8 6569.6 6869.7 6900.3 3932.7 3943.0 4004.2 4011.2 4024.0 1635.4 1653.5 1686.6 1693.0 1707.7 763.7 824.3 849.3 889.2 920.3 17464.7 17628.6 17844.0 17858.6 18314.9 773.6 787.6 790.3 804.0 817.6 8213.6 9929.6 9961.2 9964.6 10049.1 432584.0 438323.0 449246.0 451729.0 455476.0 99509.7 104523.0 106073.5 106119.5 106883.5 775.7 777.3 800.6 801.2 801.6 16201.9 16433.3 16656.0 16665.6 16980.3 43976.6 44655.8 44957.9 45608.0 47392.5 60850.4 63115.0 63677.0 64636.0 66081.0 7317.3 7471.2 7537.3 7589.7 7702.5 8716.0 12105.5 12502.7 12817.5 12860.1 9903.8 10315.8 10502.4 10843.9 10934.9 10595.9 10658.6 10670.4 10807.7 10950.1 10409.7 10965.4 10977.0 11276.2 11315.7 7380.4 7449.4 7652.4 7685.7 7762.1 8503.7 9869.2 10341.3 10351.3 10909.1
07:18:01 INFO - PROCESS | 944 |
07:18:01 INFO - PROCESS | 944 | [#3] mootools.html Cycles:180 Average:4616.58 Median:5740.03 stddev:2560.39 (44.6%) stddev-sans-first:2553.58
07:18:01 INFO - PROCESS | 944 | Values: 1054.8 1123.5 1131.9 1227.8 1418.2 6449.3 6766.3 6855.8 7140.4 7360.2 6912.6 7054.0 7305.5 7336.4 7364.0 1915.1 1928.9 1929.4 1943.8 1945.3 1887.6 1892.1 1905.8 1910.2 1912.5 1831.7 1895.8 1921.9 1927.8 1929.9 1804.7 1826.3 1877.5 1890.3 1893.2 1832.3 1856.9 1858.4 1860.2 1864.2 6950.4 7099.9 7129.6 7174.3 7187.6 4285.4 4313.4 4323.1 4385.5 4387.7 2999.1 3052.9 3053.1 3053.5 3059.5 916.4 921.2 924.2 958.5 960.2 9184.6 9316.6 9320.2 9322.6 9339.2 3596.0 3622.3 3690.7 3692.9 3693.6 7293.6 7337.9 7346.9 7349.4 7354.0 4276.7 4294.8 4314.6 4320.5 4323.4 3620.6 3643.1 4088.5 4095.7 4104.0 2865.5 2943.8 2955.7 2994.9 3087.1 883.2 906.7 914.1 930.9 931.4 684.0 800.7 807.6 813.4 838.2 4707.3 4837.0 4895.4 4917.1 4982.0 787.1 816.7 822.6 824.9 855.7 3092.0 3167.0 3233.3 3327.8 3364.4 8160.8 8493.7 8513.1 8514.8 8546.0 8178.9 8423.6 8449.7 8502.1 8514.3 6791.9 6887.8 6905.8 6922.3 6924.7 4737.1 4783.0 4807.7 4837.7 4888.3 7972.0 8021.8 8162.3 8204.8 8211.4 8099.3 8324.4 8354.7 8367.9 8381.7 5805.4 5929.2 5930.9 5956.4 5957.5 6326.7 6927.6 6987.5 7021.2 7025.1 5497.2 5753.9 6060.6 6193.4 6202.7 6168.4 6228.5 6242.5 6294.4 6313.3 5523.9 5689.3 5706.6 5714.5 5726.2 3908.7 4094.8 4141.4 4146.1 4188.6 4090.6 4196.0 4198.0 4226.9 4318.4
07:18:01 INFO - PROCESS | 944 |
07:18:01 INFO - PROCESS | 944 | [#4] prototype.html Cycles:180 Average:4344.15 Median:5005.35 stddev:1371.84 (27.4%) stddev-sans-first:1340.93
07:18:01 INFO - PROCESS | 944 | Values: 255.9 262.7 263.3 265.3 265.6 4718.7 4905.5 5061.9 5134.6 5155.6 4663.3 4968.8 4975.0 5012.5 5041.6 3722.8 3746.3 3746.3 3746.4 3753.2 3553.8 3653.1 3678.2 3752.0 3755.5 3594.4 3610.5 3637.3 3675.4 3690.3 3611.0 3628.1 3678.0 3680.7 3685.0 3134.1 3151.1 3198.7 3208.5 3212.4 4783.8 4896.9 4901.7 4920.4 4930.1 3850.4 4195.5 4245.8 4320.7 4322.8 5186.9 5187.4 5216.9 5235.9 5250.6 3268.4 3416.4 3434.7 3435.9 3440.6 5315.3 5364.9 5420.6 5449.3 5456.8 3400.9 3524.5 3529.6 3533.0 3536.7 3552.4 3559.9 3565.9 3566.6 3578.8 4199.4 4214.1 4220.2 4221.5 4235.4 5239.5 5241.5 5244.3 5249.7 5270.0 1177.7 1193.5 1205.9 1210.0 1213.4 793.9 807.8 808.3 808.5 813.3 4766.9 4918.2 4938.5 4942.3 4957.2 5377.2 5397.0 5398.6 5404.2 5411.4 4819.2 4920.8 4931.5 4933.2 4949.6 2805.7 2840.5 2849.0 2855.6 2867.3 5578.4 5599.4 5604.1 5634.6 5635.7 5466.8 5478.2 5506.6 5510.7 5531.2 5287.8 5646.2 5719.9 5757.4 5769.4 5462.6 5499.5 5502.6 5518.4 5531.8 5403.4 5412.7 5416.0 5429.9 5436.6 5489.3 5608.3 5766.5 5783.9 5791.1 3431.3 3529.5 3537.5 3547.4 3559.8 5434.7 5477.5 5505.6 5510.6 5514.6 5320.4 5322.7 5337.1 5347.7 5353.8 5084.4 5194.1 5204.4 5267.9 5383.2 4998.2 5205.6 5207.6 5233.8 5246.3 5098.5 5189.2 5236.4 5336.9 5348.3 5242.0 5262.2 5263.4 5265.5 5268.3
07:18:01 INFO - PROCESS | 944 |
07:18:01 INFO - PROCESS | 944 | [#5] yui.html Cycles:180 Average:18772.08 Median:2528.84 stddev:25704.26 (1016.4%) stddev-sans-first:25740.26
07:18:01 INFO - PROCESS | 944 | Values: 627.9 685.5 687.8 688.4 689.7 14085.0 15308.3 15341.8 15835.4 16639.8 14550.6 14900.3 15102.6 15134.5 15190.2 612.7 621.5 625.6 626.1 627.9 498.8 505.2 609.8 612.9 633.4 642.5 654.7 657.0 657.7 658.2 510.8 646.3 652.5 658.9 660.0 373.9 454.9 470.1 472.3 475.0 12259.0 12305.4 12333.8 12391.1 12399.5 12324.8 12624.0 13016.6 13210.3 13375.6 13399.7 13480.7 13486.1 13775.9 14110.6 4235.3 4338.3 4363.8 4378.8 4420.4 76847.2 82521.2 82767.6 82853.6 83760.2 10705.7 10747.8 10795.6 10832.8 10839.6 19736.1 19771.6 19841.0 19842.1 19857.1 7443.3 7463.3 7546.5 7561.2 7614.5 3832.5 3842.6 3948.3 4026.1 4043.8 2620.5 2871.9 2879.8 2884.1 2894.8 501.0 512.0 521.5 529.8 533.7 626.8 649.6 653.8 654.5 669.1 9213.1 9252.3 9321.3 9576.0 9621.7 634.6 649.3 651.7 652.4 660.5 4500.2 4602.7 4614.4 4664.6 4757.5 62739.0 65195.7 68094.7 76088.0 121544.4 58310.4 59607.0 59993.4 60414.7 70684.0 87185.1 88111.4 89177.6 89575.0 91205.5 9137.4 9936.2 9962.2 10046.0 10059.2 46829.8 48931.3 49392.0 49476.0 50449.7 70871.0 77187.9 77260.6 77392.2 77914.6 10656.2 10790.7 10853.8 10913.8 11054.7 14432.3 14809.8 14943.3 14965.6 15264.4 13366.2 13475.3 13827.6 14050.5 14089.9 12869.9 13449.8 13977.2 14539.6 14648.2 13528.8 13819.9 13821.7 14119.6 14375.3 11745.9 12173.8 12239.9 12467.3 12642.1 11352.0 11971.5 12041.9 12074.8 12116.5
07:18:01 INFO - PROCESS | 944 | -------- Summary: end --------
07:18:01 INFO - PROCESS | 944 |
07:18:02 INFO - TEST-INFO | 944: exit 0
07:18:03 INFO - TEST-OK | dromaeo_css | took 1120792ms
07:18:03 INFO - TEST-START | kraken
07:18:03 INFO - operating with platform_type : w7_
07:18:03 INFO - Initialising browser for kraken test...
07:18:03 INFO - TEST-INFO | started process 1456 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpgcalr5\profile http://localhost:49752/getInfo.html)
07:18:07 INFO - PROCESS | 1456 | __metrics Screen width/height:1600/1200
07:18:07 INFO - PROCESS | 1456 | colorDepth:24
07:18:07 INFO - PROCESS | 1456 | Browser inner width/height: 1010/674
07:18:07 INFO - PROCESS | 1456 | __metrics
07:18:08 INFO - PROCESS | 1456 | 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"
07:18:08 INFO - PROCESS | 1456 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
07:18:08 INFO - PROCESS | 1456 | console.error:
07:18:08 INFO - PROCESS | 1456 | Message: Error: Connection closed before committing the transaction.
07:18:08 INFO - PROCESS | 1456 | Stack:
07:18:08 INFO - PROCESS | 1456 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:937:23
07:18:08 INFO - PROCESS | 1456 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7
07:18:08 INFO - PROCESS | 1456 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11
07:18:08 INFO - PROCESS | 1456 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7
07:18:08 INFO - PROCESS | 1456 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7
07:18:08 INFO - PROCESS | 1456 | TaskImpl_run@resource://gre/modules/Task.jsm:327:15
07:18:08 INFO - PROCESS | 1456 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9
07:18:08 INFO - PROCESS | 1456 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15
07:18:08 INFO - PROCESS | 1456 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5
07:18:08 INFO - PROCESS | 1456 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29
07:18:08 INFO - PROCESS | 1456 | Barrier/this.client.addBlocker/promise13.1%
07:19:06 INFO - TinderboxPrint: I/O read bytes / time
104,192,512 / 1,570,250
07:19:06 INFO - TinderboxPrint: I/O write bytes / time
479,683,072 / 4,099,410
07:19:06 INFO - TinderboxPrint: CPU idle
8,268.2 (86.9%)
07:19:06 INFO - TinderboxPrint: CPU user
1,208.6 (12.7%)
07:19:06 INFO - install - Wall time: 4s; CPU: 7.0%; Read bytes: 32768; Write bytes: 40781312; Read time: 59830; Write time: 785660
07:19:06 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 104159744; Write bytes: 399145984; Read time: 1510420; Write time: 3269560
07:19:07 INFO - Running post-run listener: _upload_blobber_files
07:19:07 INFO - Blob upload gear active.
07:19:07 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir.
07:19:07 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
07:19:07 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']
07:19:07 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
07:19:07 INFO - (blobuploader) - INFO - Open directory for files ...
07:19:07 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log ...
07:19:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
07:19:07 INFO - (blobuploader) - INFO - Uploading, attempt #1.
07:19:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded
07:19:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
07:19:08 INFO - (blobuploader) - INFO - Done attempting.
07:19:08 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log ...
07:19:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
07:19:08 INFO - (blobuploader) - INFO - Uploading, attempt #1.
07:19:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded
07:19:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
07:19:09 INFO - (blobuploader) - INFO - Done attempting.
07:19:09 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ...
07:19:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
07:19:09 INFO - (blobuploader) - INFO - Uploading, attempt #1.
07:19:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
07:19:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
07:19:17 INFO - (blobuploader) - INFO - Done attempting.
07:19:17 INFO - (blobuploader) - INFO - Iteration through files over.
07:19:17 INFO - Return code: 0
07:19:17 INFO - rmtree: C:\slave\test\build\uploaded_files.json
07:19:17 INFO - Using _rmtree_windows ...
07:19:17 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1
07:19:17 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a9d19ba912c06201a2a166c3dca8ad7fe382ff379cfe2a6d823849e1f60751eb2f8ae66ecfffa18b980783efd793e46c3e1292a78a125e3c1fff19561e0f1fca", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/939f3c25594ee6add3c8e735d87cc037b659f7de9d4c28e2a1b8c038135193de6895455becb65f83b81b5dfd7d973a1503b2e84f4fb73c5630ebd159acf77d52", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dbbf556eccb4432c9fd4c1ab495763f288fd23ba1085e2acd1b4b5115a9765056809e8f840db4f14cefd8e0d4f021f7a34832cebf9fd695417923f53024d85cd"}
07:19:17 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files
07:19:17 INFO - Writing to file C:\slave\test\properties\blobber_files
07:19:17 INFO - Contents:
07:19:17 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a9d19ba912c06201a2a166c3dca8ad7fe382ff379cfe2a6d823849e1f60751eb2f8ae66ecfffa18b980783efd793e46c3e1292a78a125e3c1fff19561e0f1fca", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/939f3c25594ee6add3c8e735d87cc037b659f7de9d4c28e2a1b8c038135193de6895455becb65f83b81b5dfd7d973a1503b2e84f4fb73c5630ebd159acf77d52", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dbbf556eccb4432c9fd4c1ab495763f288fd23ba1085e2acd1b4b5115a9765056809e8f840db4f14cefd8e0d4f021f7a34832cebf9fd695417923f53024d85cd"}
07:19:17 INFO - Running post-run listener: copy_logs_to_upload_dir
07:19:17 INFO - Copying logs to upload dir...
07:19:17 INFO - mkdir: C:\slave\test\build\upload\logs
07:19:17 INFO - Copying logs to upload dir...
07:19:17 INFO - Using _rmtree_windows ...
07:19:17 INFO - Using _rmtree_windows ...
07:19:17 INFO - Using _rmtree_windows ...
07:19:17 INFO - Using _rmtree_windows ...
07:19:17 INFO - Using _rmtree_windows ...
07:19:17 INFO - Using _rmtree_windows ...
07:19:17 INFO - Using _rmtree_windows ...
program finished with exit code 0
elapsedTime=1476.875000
========= master_lag: -0.07 =========
========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 36 secs) (at 2016-10-18 07:19:17.988725) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-18 07:19:17.994925) =========
'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-107
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-107
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-107
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/a9d19ba912c06201a2a166c3dca8ad7fe382ff379cfe2a6d823849e1f60751eb2f8ae66ecfffa18b980783efd793e46c3e1292a78a125e3c1fff19561e0f1fca", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/939f3c25594ee6add3c8e735d87cc037b659f7de9d4c28e2a1b8c038135193de6895455becb65f83b81b5dfd7d973a1503b2e84f4fb73c5630ebd159acf77d52", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dbbf556eccb4432c9fd4c1ab495763f288fd23ba1085e2acd1b4b5115a9765056809e8f840db4f14cefd8e0d4f021f7a34832cebf9fd695417923f53024d85cd"}
build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476786616/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/1476786616/firefox-52.0a1.en-US.win32.zip'
blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a9d19ba912c06201a2a166c3dca8ad7fe382ff379cfe2a6d823849e1f60751eb2f8ae66ecfffa18b980783efd793e46c3e1292a78a125e3c1fff19561e0f1fca", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/939f3c25594ee6add3c8e735d87cc037b659f7de9d4c28e2a1b8c038135193de6895455becb65f83b81b5dfd7d973a1503b2e84f4fb73c5630ebd159acf77d52", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dbbf556eccb4432c9fd4c1ab495763f288fd23ba1085e2acd1b4b5115a9765056809e8f840db4f14cefd8e0d4f021f7a34832cebf9fd695417923f53024d85cd"}'
========= master_lag: 0.01 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-18 07:19:18.108653) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 07:19:18.109010) =========
'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-107
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-107
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-107
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-18 07:19:18.220222) =========
========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-10-18 07:19:18.220575) =========
========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-10-18 07:19:18.741214) =========
========= Total master_lag: 6.35 =========