builder: fx-team_win7_ix_test-dromaeojs-pgo
slave: t-w732-ix-034
starttime: 1476974670.14
results: success (0)
buildid: 20161020070833
builduid: e2f64cac4c6846b891d58882763cc9fd
revision: 99a239e1866a57f987b08dad796528e4ea30e622
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-20 07:44:30.138546) =========
master: http://buildbot-master110.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-20 07:44:30.139123) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-20 07:44:30.139462) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-20 07:44:30.302692) =========
========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-10-20 07:44:30.303084) =========
'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-034
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-034
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-034
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
--07:44:31-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
=> `archiver_client.py'
Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25
Connecting to hg.mozilla.org|63.245.215.102|:443... connected.
WARNING: Certificate verification error for hg.mozilla.org: certificate signature failure
HTTP request sent, awaiting response... 200 Script output follows
Length: 12,179 (12K) [text/x-python]
0K .......... . 100% 2.27 GB/s
07:44:32 (2.27 GB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=1.485000
========= master_lag: 3.21 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-10-20 07:44:34.997449) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 19 secs) (at 2016-10-20 07:44:34.997772) =========
'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-034
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-034
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-034
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=19.701000
========= master_lag: 0.03 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 19 secs) (at 2016-10-20 07:44:54.724889) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-20 07:44:54.725255) =========
'bash' '-c' u'python archiver_client.py mozharness --repo integration/fx-team --rev 99a239e1866a57f987b08dad796528e4ea30e622 --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 99a239e1866a57f987b08dad796528e4ea30e622 --destination scripts --debug']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-034
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-034
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-034
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
2016-10-20 07:44:55,569 truncating revision to first 12 chars
2016-10-20 07:44:55,569 Setting DEBUG logging.
2016-10-20 07:44:55,569 attempt 1/10
2016-10-20 07:44:55,569 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/99a239e1866a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2016-10-20 07:44:56,654 unpacking tar archive at: fx-team-99a239e1866a/testing/mozharness/
2016-10-20 07:44:56,776 skipping symlink on windows: configs/single_locale/linux32.py
program finished with exit code 0
elapsedTime=1.897000
========= master_lag: 0.04 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-20 07:44:56.665469) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-20 07:44:56.665791) =========
script_repo_revision: 99a239e1866a57f987b08dad796528e4ea30e622
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-20 07:44:56.666186) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-20 07:44:56.666467) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-20 07:44:56.676318) =========
========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 41 secs) (at 2016-10-20 07:44:56.676596) =========
'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-034
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-034
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-034
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
07:44:57 INFO - MultiFileLogger online at 20161020 07:44:57 in C:\slave\test
07:44:57 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
07:44:57 INFO - Dumping config to C:\slave\test\logs\localconfig.json.
07:44:57 INFO - {'append_to_log': False,
07:44:57 INFO - 'base_work_dir': 'C:\\slave\\test',
07:44:57 INFO - 'blob_upload_branch': 'Fx-Team',
07:44:57 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt',
07:44:57 INFO - 'branch': 'Fx-Team',
07:44:57 INFO - 'buildbot_json_path': 'buildprops.json',
07:44:57 INFO - 'config_files': ('talos/windows_config.py',),
07:44:57 INFO - 'default_actions': ('clobber',
07:44:57 INFO - 'read-buildbot-config',
07:44:57 INFO - 'download-and-extract',
07:44:57 INFO - 'populate-webroot',
07:44:57 INFO - 'create-virtualenv',
07:44:57 INFO - 'install',
07:44:57 INFO - 'run-tests'),
07:44:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
07:44:57 INFO - 'download_minidump_stackwalk': True,
07:44:57 INFO - 'download_symbols': 'ondemand',
07:44:57 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python',
07:44:57 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'),
07:44:57 INFO - 'hg': 'c:/mozilla-build/hg/hg',
07:44:57 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python',
07:44:57 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'),
07:44:57 INFO - 'python': 'c:/mozilla-build/python27/python.exe',
07:44:57 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe',
07:44:57 INFO - 'C:/mozilla-build/tooltool.py'),
07:44:57 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe',
07:44:57 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')},
07:44:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
07:44:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
07:44:57 INFO - 'installer_path': 'installer.exe',
07:44:57 INFO - 'log_level': 'info',
07:44:57 INFO - 'log_name': 'talos',
07:44:57 INFO - 'log_to_console': True,
07:44:57 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe',
07:44:57 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe',
07:44:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest',
07:44:57 INFO - 'opt_config_files': (),
07:44:57 INFO - 'pip_index': False,
07:44:57 INFO - 'sps_profile': False,
07:44:57 INFO - 'sps_profile_interval': 0,
07:44:57 INFO - 'suite': 'dromaeojs',
07:44:57 INFO - 'system_bits': '32',
07:44:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
07:44:57 INFO - 'title': 'T-W732-IX-034',
07:44:57 INFO - 'use_talos_json': True,
07:44:57 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'),
07:44:57 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv',
07:44:57 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll',
07:44:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
07:44:57 INFO - 'work_dir': 'build'}
07:44:57 INFO - [mozharness: 2016-10-20 14:44:57.648000Z] Running clobber step.
07:44:57 INFO - Running pre-action listener: _resource_record_pre_action
07:44:57 INFO - Running main action method: clobber
07:44:57 INFO - rmtree: C:\slave\test\build
07:44:57 INFO - Using _rmtree_windows ...
07:44:57 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1
07:46:36 INFO - Running post-action listener: _resource_record_post_action
07:46:36 INFO - [mozharness: 2016-10-20 14:46:36.191000Z] Finished clobber step (success)
07:46:36 INFO - [mozharness: 2016-10-20 14:46:36.191000Z] Running read-buildbot-config step.
07:46:36 INFO - Running pre-action listener: _resource_record_pre_action
07:46:36 INFO - Running main action method: read_buildbot_config
07:46:36 INFO - Using buildbot properties:
07:46:36 INFO - {
07:46:36 INFO - "project": "",
07:46:36 INFO - "product": "firefox",
07:46:36 INFO - "script_repo_revision": "production",
07:46:36 INFO - "builddir": "fx-team_win7_ix_test-dromaeojs",
07:46:36 INFO - "repository": "",
07:46:36 INFO - "buildername": "Windows 7 32-bit fx-team pgo talos dromaeojs",
07:46:36 INFO - "buildid": "20161020070833",
07:46:36 INFO - "stage_platform": "win32-pgo",
07:46:36 INFO - "basedir": "C:\\slave\\test",
07:46:36 INFO - "buildnumber": 238,
07:46:36 INFO - "platform": "win7_ix",
07:46:36 INFO - "master": "http://buildbot-master110.bb.releng.scl3.mozilla.com:8201/",
07:46:36 INFO - "slavebuilddir": "test",
07:46:36 INFO - "branch": "fx-team",
07:46:36 INFO - "revision": "99a239e1866a57f987b08dad796528e4ea30e622",
07:46:36 INFO - "slavename": "t-w732-ix-034",
07:46:36 INFO - "builduid": "e2f64cac4c6846b891d58882763cc9fd",
07:46:36 INFO - "repo_path": "integration/fx-team"
07:46:36 INFO - }
07:46:36 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476959407/firefox-52.0a1.en-US.win32.test_packages.json.
07:46:36 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476959407/firefox-52.0a1.en-US.win32.zip.
07:46:36 INFO - Running post-action listener: _resource_record_post_action
07:46:36 INFO - [mozharness: 2016-10-20 14:46:36.194000Z] Finished read-buildbot-config step (success)
07:46:36 INFO - [mozharness: 2016-10-20 14:46:36.195000Z] Running download-and-extract step.
07:46:36 INFO - Running pre-action listener: _resource_record_pre_action
07:46:36 INFO - Running main action method: download_and_extract
07:46:36 INFO - mkdir: C:\slave\test\build\tests
07:46:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
07:46:36 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476959407/firefox-52.0a1.en-US.win32.test_packages.json
07:46:36 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476959407/firefox-52.0a1.en-US.win32.test_packages.json to C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json
07:46:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476959407/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
07:46:36 INFO - Downloaded 1218 bytes.
07:46:36 INFO - Reading from file C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json
07:46:36 INFO - Using the following test package requirements:
07:46:36 INFO - {u'common': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
07:46:36 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
07:46:36 INFO - u'firefox-52.0a1.en-US.win32.cppunittest.tests.zip'],
07:46:36 INFO - u'gtest': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
07:46:36 INFO - u'jittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
07:46:36 INFO - u'jsshell-win32.zip'],
07:46:36 INFO - u'mochitest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
07:46:36 INFO - u'firefox-52.0a1.en-US.win32.mochitest.tests.zip'],
07:46:36 INFO - u'mozbase': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
07:46:36 INFO - u'reftest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
07:46:36 INFO - u'firefox-52.0a1.en-US.win32.reftest.tests.zip'],
07:46:36 INFO - u'talos': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
07:46:36 INFO - u'firefox-52.0a1.en-US.win32.talos.tests.zip'],
07:46:36 INFO - u'web-platform': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
07:46:36 INFO - u'firefox-52.0a1.en-US.win32.web-platform.tests.zip'],
07:46:36 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
07:46:36 INFO - u'firefox-52.0a1.en-US.win32.xpcshell.tests.zip']}
07:46:36 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip'] for test suite category: common
07:46:36 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/1476959407/firefox-52.0a1.en-US.win32.common.tests.zip
07:46:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476959407/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1
07:46:36 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476959407/firefox-52.0a1.en-US.win32.common.tests.zip into memory
07:46:36 INFO - Http code: 200
07:46:36 INFO - via: 1.1 bb8a7ff26b131d51953dc2d3e19a2751.cloudfront.net (CloudFront)
07:46:36 INFO - x-amz-cf-id: 9A6OC4Gc8WsyuGF15B0RfSGZHhjEToGIw8Gd3lMTC3dRBL-x-MGlpw==
07:46:36 INFO - x-amz-replication-status: COMPLETED
07:46:36 INFO - x-amz-version-id: xkaXh9XNt8PDAhtlj_CEKvNmn067rbaP
07:46:37 INFO - Expected file size: 17262980
07:46:37 INFO - Obtained file size: 17262980
07:47:08 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
07:47:08 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/1476959407/firefox-52.0a1.en-US.win32.common.tests.zip
07:47:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476959407/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1
07:47:08 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476959407/firefox-52.0a1.en-US.win32.common.tests.zip into memory
07:47:09 INFO - Http code: 200
07:47:09 INFO - via: 1.1 a1aa00de8387e7235a256b2a5b73ede8.cloudfront.net (CloudFront)
07:47:09 INFO - x-amz-cf-id: 5VfDJVQLp3GA6YVI0mDEWdYuKJFlDzMusR1uLw-b6jFDjHzmJXpEFw==
07:47:09 INFO - x-amz-replication-status: COMPLETED
07:47:09 INFO - x-amz-version-id: xkaXh9XNt8PDAhtlj_CEKvNmn067rbaP
07:47:09 INFO - Expected file size: 17262980
07:47:09 INFO - Obtained file size: 17262980
07:47:23 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/1476959407/firefox-52.0a1.en-US.win32.talos.tests.zip
07:47:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476959407/firefox-52.0a1.en-US.win32.talos.tests.zip'}, attempt #1
07:47:23 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476959407/firefox-52.0a1.en-US.win32.talos.tests.zip into memory
07:47:23 INFO - Http code: 200
07:47:23 INFO - via: 1.1 73fa5a1ee49cf827e596b502b5927eab.cloudfront.net (CloudFront)
07:47:23 INFO - x-amz-cf-id: 4cS12021DhDP_XRGBJMf6jo9FIN8EWCpV3ZnZKnMHpSErEydpXqk0A==
07:47:23 INFO - x-amz-replication-status: COMPLETED
07:47:23 INFO - x-amz-version-id: D8r3DwIIlT0bj.C3eRhRUyCiGsjsQKxI
07:47:23 INFO - Expected file size: 14409028
07:47:23 INFO - Obtained file size: 14409028
07:47:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
07:47:26 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476959407/firefox-52.0a1.en-US.win32.zip
07:47:26 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476959407/firefox-52.0a1.en-US.win32.zip to C:\slave\test\build\firefox-52.0a1.en-US.win32.zip
07:47:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476959407/firefox-52.0a1.en-US.win32.zip', 'file_name': 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.zip'}, attempt #1
07:47:27 INFO - Downloaded 54321943 bytes.
07:47:27 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476959407/firefox-52.0a1.en-US.win32.zip
07:47:27 INFO - mkdir: C:\slave\test\properties
07:47:27 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url
07:47:27 INFO - Writing to file C:\slave\test\properties\build_url
07:47:27 INFO - Contents:
07:47:27 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476959407/firefox-52.0a1.en-US.win32.zip
07:47:27 INFO - Running post-action listener: _resource_record_post_action
07:47:27 INFO - Running post-action listener: set_extra_try_arguments
07:47:27 INFO - [mozharness: 2016-10-20 14:47:27.624000Z] Finished download-and-extract step (success)
07:47:27 INFO - [mozharness: 2016-10-20 14:47:27.624000Z] Running populate-webroot step.
07:47:27 INFO - Running pre-action listener: _resource_record_pre_action
07:47:27 INFO - Running main action method: populate_webroot
07:47:27 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']},
07:47:27 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']},
07:47:27 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome',
07:47:27 INFO - u'--tppagecycles',
07:47:27 INFO - u'7'],
07:47:27 INFO - u'tests': [u'tsvgm']}},
07:47:27 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'],
07:47:27 INFO - u'tests': [u'tresize', u'tcanvasmark']},
07:47:27 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']},
07:47:27 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'],
07:47:27 INFO - u'tests': [u'dromaeo_css', u'kraken']},
07:47:27 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']},
07:47:27 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
07:47:27 INFO - u'talos_options': [u'--disable-e10s'],
07:47:27 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
07:47:27 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
07:47:27 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
07:47:27 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
07:47:27 INFO - u'talos_options': [u'--disable-e10s'],
07:47:27 INFO - u'tests': [u'damp', u'tps']},
07:47:27 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
07:47:27 INFO - u'tests': [u'damp', u'tps']},
07:47:27 INFO - u'g3': {u'talos_options': [u'--disable-e10s'],
07:47:27 INFO - u'tests': [u'dromaeo_dom']},
07:47:27 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']},
07:47:27 INFO - u'g4': {u'talos_options': [u'--disable-e10s'],
07:47:27 INFO - u'tests': [u'basic_compositor_video']},
07:47:27 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']},
07:47:27 INFO - u'other': {u'talos_options': [u'--disable-e10s'],
07:47:27 INFO - u'tests': [u'a11yr',
07:47:27 INFO - u'ts_paint',
07:47:27 INFO - u'tpaint',
07:47:27 INFO - u'sessionrestore',
07:47:27 INFO - u'sessionrestore_no_auto_restore',
07:47:27 INFO - u'tabpaint']},
07:47:27 INFO - u'other-e10s': {u'tests': [u'a11yr',
07:47:27 INFO - u'ts_paint',
07:47:27 INFO - u'tpaint',
07:47:27 INFO - u'sessionrestore',
07:47:27 INFO - u'sessionrestore_no_auto_restore',
07:47:27 INFO - u'tabpaint']},
07:47:27 INFO - u'other-e10s_l64': {u'tests': [u'a11yr',
07:47:27 INFO - u'ts_paint',
07:47:27 INFO - u'tpaint',
07:47:27 INFO - u'sessionrestore',
07:47:27 INFO - u'sessionrestore_no_auto_restore',
07:47:27 INFO - u'tabpaint']},
07:47:27 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr',
07:47:27 INFO - u'ts_paint',
07:47:27 INFO - u'tpaint',
07:47:27 INFO - u'sessionrestore',
07:47:27 INFO - u'sessionrestore_no_auto_restore',
07:47:27 INFO - u'tabpaint']},
07:47:27 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'],
07:47:27 INFO - u'tests': [u'a11yr',
07:47:27 INFO - u'ts_paint',
07:47:27 INFO - u'tpaint',
07:47:27 INFO - u'sessionrestore',
07:47:27 INFO - u'sessionrestore_no_auto_restore',
07:47:27 INFO - u'tabpaint']},
07:47:27 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'],
07:47:27 INFO - u'tests': [u'a11yr',
07:47:27 INFO - u'ts_paint',
07:47:27 INFO - u'tpaint',
07:47:27 INFO - u'sessionrestore',
07:47:27 INFO - u'sessionrestore_no_auto_restore',
07:47:27 INFO - u'tabpaint']},
07:47:27 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'],
07:47:27 INFO - u'tests': [u'tsvgx',
07:47:27 INFO - u'tsvgr_opacity',
07:47:27 INFO - u'tart',
07:47:27 INFO - u'tscrollx',
07:47:27 INFO - u'cart']},
07:47:27 INFO - u'svgr-e10s': {u'tests': [u'tsvgx',
07:47:27 INFO - u'tsvgr_opacity',
07:47:27 INFO - u'tart',
07:47:27 INFO - u'tscrollx',
07:47:27 INFO - u'cart']},
07:47:27 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
07:47:27 INFO - u'talos_options': [u'--disable-e10s'],
07:47:27 INFO - u'tests': [u'tp5o']},
07:47:27 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
07:47:27 INFO - u'tests': [u'tp5o']},
07:47:27 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
07:47:27 INFO - u'talos_options': [u'--disable-e10s',
07:47:27 INFO - u'--xperf_path',
07:47:27 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
07:47:27 INFO - u'tests': [u'tp5n']},
07:47:27 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
07:47:27 INFO - u'talos_options': [u'--xperf_path',
07:47:27 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
07:47:27 INFO - u'tests': [u'tp5n']}},
07:47:27 INFO - u'talos.zip': {u'path': u'',
07:47:27 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}}
07:47:27 INFO - Running post-action listener: _resource_record_post_action
07:47:27 INFO - [mozharness: 2016-10-20 14:47:27.639000Z] Finished populate-webroot step (success)
07:47:27 INFO - [mozharness: 2016-10-20 14:47:27.639000Z] Running create-virtualenv step.
07:47:27 INFO - Running pre-action listener: _resource_record_pre_action
07:47:27 INFO - Running main action method: create_virtualenv
07:47:27 INFO - Creating virtualenv C:\slave\test\build/venv
07:47:27 INFO - mkdir: C:\slave\test\build\venv\Scripts
07:47:28 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll
07:47:28 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'!
07:47:28 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
07:47:28 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv
07:47:49 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe
07:47:53 INFO - Installing distribute......................................................................................................................................................................................done.
07:47:54 INFO - Return code: 0
07:47:54 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version']
07:47:54 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version
07:47:54 INFO - Reading from file tmpfile_stdout
07:47:54 INFO - Output received:
07:47:54 INFO - pip 0.8.2 from c:\slave\test\build\venv\lib\site-packages\pip-0.8.2-py2.7.egg (python 2.7)
07:47:54 INFO - Using _rmtree_windows ...
07:47:54 INFO - Using _rmtree_windows ...
07:47:54 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv
07:47:54 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')]}
07:47:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
07:47:54 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')]}
07:47:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
07:47:54 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 0x018D8640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01912E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01852B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0192CE90>, '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 0x0122A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01998520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-034', 'USERDOMAIN': 'T-W732-IX-034', '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-034', '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
07:47:55 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
07:47:55 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
07:47:55 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
07:47:55 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
07:47:55 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
07:47:55 INFO - 'COMPUTERNAME': 'T-W732-IX-034',
07:47:55 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
07:47:55 INFO - 'CYGWINBASE': 'C:\\cygwin',
07:47:55 INFO - 'DCLOCATION': 'SCL3',
07:47:55 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
07:47:55 INFO - 'FP_NO_HOST_CHECK': 'NO',
07:47:55 INFO - 'HOMEDRIVE': 'C:',
07:47:55 INFO - 'HOMEPATH': '\\Users\\cltbld',
07:47:55 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
07:47:55 INFO - 'KTS_VERSION': '1.19c',
07:47:55 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
07:47:55 INFO - 'LOGONSERVER': '\\\\T-W732-IX-034',
07:47:55 INFO - 'MONDIR': 'C:\\Monitor_config\\',
07:47:55 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
07:47:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
07:47:55 INFO - 'MOZ_NO_REMOTE': '1',
07:47:55 INFO - 'NO_EM_RESTART': '1',
07:47:55 INFO - 'NUMBER_OF_PROCESSORS': '8',
07:47:55 INFO - 'OS': 'Windows_NT',
07:47:55 INFO - 'OURDRIVE': 'C:',
07:47:55 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;',
07:47:55 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
07:47:55 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
07:47:55 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
07:47:55 INFO - 'PROCESSOR_LEVEL': '6',
07:47:55 INFO - 'PROCESSOR_REVISION': '1e05',
07:47:55 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
07:47:55 INFO - 'PROGRAMFILES': 'C:\\Program Files',
07:47:55 INFO - 'PROMPT': '$P$G',
07:47:55 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
07:47:55 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
07:47:55 INFO - 'PUBLIC': 'C:\\Users\\Public',
07:47:55 INFO - 'PWD': 'C:\\slave\\test',
07:47:55 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
07:47:55 INFO - 'SLAVEDIR': 'C:\\slave\\',
07:47:55 INFO - 'SYSTEMDRIVE': 'C:',
07:47:55 INFO - 'SYSTEMROOT': 'C:\\windows',
07:47:55 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:47:55 INFO - 'TEST1': 'testie',
07:47:55 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:47:55 INFO - 'USERDOMAIN': 'T-W732-IX-034',
07:47:55 INFO - 'USERNAME': 'cltbld',
07:47:55 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
07:47:55 INFO - 'WINDIR': 'C:\\windows',
07:47:55 INFO - 'WINDOWS_TRACING_FLAGS': '3',
07:47:55 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
07:47:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
07:47:55 INFO - Ignoring indexes: http://pypi.python.org/simple/
07:47:55 INFO - Downloading/unpacking pip>=1.5
07:48:00 INFO - Running setup.py egg_info for package pip
07:48:00 INFO - warning: no files found matching 'pip\cacert.pem'
07:48:00 INFO - warning: no files found matching '*.html' under directory 'docs'
07:48:00 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
07:48:00 INFO - no previously-included directories found matching 'docs\_build\_sources'
07:48:00 INFO - Installing collected packages: pip
07:48:00 INFO - Found existing installation: pip 0.8.2
07:48:00 INFO - Uninstalling pip:
07:48:00 INFO - Successfully uninstalled pip
07:48:00 INFO - Running setup.py install for pip
07:48:00 INFO - warning: no files found matching 'pip\cacert.pem'
07:48:00 INFO - warning: no files found matching '*.html' under directory 'docs'
07:48:00 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
07:48:00 INFO - no previously-included directories found matching 'docs\_build\_sources'
07:48:00 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts
07:48:00 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts
07:48:00 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts
07:48:00 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts
07:48:00 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts
07:48:00 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts
07:48:00 INFO - Successfully installed pip
07:48:00 INFO - Cleaning up...
07:48:00 INFO - Return code: 0
07:48:00 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv
07:48:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
07:48:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
07:48:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
07:48:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
07:48:00 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 0x018D8640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01912E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01852B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0192CE90>, '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 0x0122A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01998520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-034', 'USERDOMAIN': 'T-W732-IX-034', '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-034', '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
07:48:00 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
07:48:00 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
07:48:00 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
07:48:00 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
07:48:00 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
07:48:00 INFO - 'COMPUTERNAME': 'T-W732-IX-034',
07:48:00 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
07:48:00 INFO - 'CYGWINBASE': 'C:\\cygwin',
07:48:00 INFO - 'DCLOCATION': 'SCL3',
07:48:00 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
07:48:00 INFO - 'FP_NO_HOST_CHECK': 'NO',
07:48:00 INFO - 'HOMEDRIVE': 'C:',
07:48:00 INFO - 'HOMEPATH': '\\Users\\cltbld',
07:48:00 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
07:48:00 INFO - 'KTS_VERSION': '1.19c',
07:48:00 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
07:48:00 INFO - 'LOGONSERVER': '\\\\T-W732-IX-034',
07:48:00 INFO - 'MONDIR': 'C:\\Monitor_config\\',
07:48:00 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
07:48:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
07:48:00 INFO - 'MOZ_NO_REMOTE': '1',
07:48:00 INFO - 'NO_EM_RESTART': '1',
07:48:00 INFO - 'NUMBER_OF_PROCESSORS': '8',
07:48:00 INFO - 'OS': 'Windows_NT',
07:48:00 INFO - 'OURDRIVE': 'C:',
07:48:00 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;',
07:48:00 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
07:48:00 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
07:48:00 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
07:48:00 INFO - 'PROCESSOR_LEVEL': '6',
07:48:00 INFO - 'PROCESSOR_REVISION': '1e05',
07:48:00 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
07:48:00 INFO - 'PROGRAMFILES': 'C:\\Program Files',
07:48:00 INFO - 'PROMPT': '$P$G',
07:48:00 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
07:48:00 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
07:48:00 INFO - 'PUBLIC': 'C:\\Users\\Public',
07:48:00 INFO - 'PWD': 'C:\\slave\\test',
07:48:00 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
07:48:00 INFO - 'SLAVEDIR': 'C:\\slave\\',
07:48:00 INFO - 'SYSTEMDRIVE': 'C:',
07:48:00 INFO - 'SYSTEMROOT': 'C:\\windows',
07:48:00 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:48:00 INFO - 'TEST1': 'testie',
07:48:00 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:48:00 INFO - 'USERDOMAIN': 'T-W732-IX-034',
07:48:00 INFO - 'USERNAME': 'cltbld',
07:48:00 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
07:48:00 INFO - 'WINDIR': 'C:\\windows',
07:48:00 INFO - 'WINDOWS_TRACING_FLAGS': '3',
07:48:00 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
07:48:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
07:48:02 INFO - Ignoring indexes: https://pypi.python.org/simple/
07:48:02 INFO - Downloading/unpacking psutil>=3.1.1
07:48:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
07:48:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
07:48:03 INFO - Installing collected packages: psutil
07:48:03 INFO - Successfully installed psutil
07:48:03 INFO - Cleaning up...
07:48:03 INFO - Return code: 0
07:48:03 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv
07:48:03 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')]}
07:48:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
07:48:03 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')]}
07:48:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
07:48:03 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 0x018D8640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01912E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01852B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0192CE90>, '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 0x0122A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01998520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-034', 'USERDOMAIN': 'T-W732-IX-034', '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-034', '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
07:48:03 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
07:48:03 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
07:48:03 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
07:48:03 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
07:48:03 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
07:48:03 INFO - 'COMPUTERNAME': 'T-W732-IX-034',
07:48:03 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
07:48:03 INFO - 'CYGWINBASE': 'C:\\cygwin',
07:48:03 INFO - 'DCLOCATION': 'SCL3',
07:48:03 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
07:48:03 INFO - 'FP_NO_HOST_CHECK': 'NO',
07:48:03 INFO - 'HOMEDRIVE': 'C:',
07:48:03 INFO - 'HOMEPATH': '\\Users\\cltbld',
07:48:03 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
07:48:03 INFO - 'KTS_VERSION': '1.19c',
07:48:03 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
07:48:03 INFO - 'LOGONSERVER': '\\\\T-W732-IX-034',
07:48:03 INFO - 'MONDIR': 'C:\\Monitor_config\\',
07:48:03 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
07:48:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
07:48:03 INFO - 'MOZ_NO_REMOTE': '1',
07:48:03 INFO - 'NO_EM_RESTART': '1',
07:48:03 INFO - 'NUMBER_OF_PROCESSORS': '8',
07:48:03 INFO - 'OS': 'Windows_NT',
07:48:03 INFO - 'OURDRIVE': 'C:',
07:48:03 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;',
07:48:03 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
07:48:03 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
07:48:03 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
07:48:03 INFO - 'PROCESSOR_LEVEL': '6',
07:48:03 INFO - 'PROCESSOR_REVISION': '1e05',
07:48:03 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
07:48:03 INFO - 'PROGRAMFILES': 'C:\\Program Files',
07:48:03 INFO - 'PROMPT': '$P$G',
07:48:03 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
07:48:03 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
07:48:03 INFO - 'PUBLIC': 'C:\\Users\\Public',
07:48:03 INFO - 'PWD': 'C:\\slave\\test',
07:48:03 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
07:48:03 INFO - 'SLAVEDIR': 'C:\\slave\\',
07:48:03 INFO - 'SYSTEMDRIVE': 'C:',
07:48:03 INFO - 'SYSTEMROOT': 'C:\\windows',
07:48:03 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:48:03 INFO - 'TEST1': 'testie',
07:48:03 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:48:03 INFO - 'USERDOMAIN': 'T-W732-IX-034',
07:48:03 INFO - 'USERNAME': 'cltbld',
07:48:03 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
07:48:03 INFO - 'WINDIR': 'C:\\windows',
07:48:03 INFO - 'WINDOWS_TRACING_FLAGS': '3',
07:48:03 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
07:48:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
07:48:03 INFO - Ignoring indexes: https://pypi.python.org/simple/
07:48:03 INFO - Downloading/unpacking mozsystemmonitor==0.3
07:48:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
07:48:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
07:48:04 INFO - Downloading mozsystemmonitor-0.3.tar.gz
07:48:04 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor
07:48:05 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)
07:48:05 INFO - Installing collected packages: mozsystemmonitor
07:48:05 INFO - Running setup.py install for mozsystemmonitor
07:48:05 INFO - Successfully installed mozsystemmonitor
07:48:05 INFO - Cleaning up...
07:48:05 INFO - Return code: 0
07:48:05 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv
07:48:05 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')]}
07:48:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
07:48:05 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')]}
07:48:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
07:48:05 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 0x018D8640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01912E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01852B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0192CE90>, '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 0x0122A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01998520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-034', 'USERDOMAIN': 'T-W732-IX-034', '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-034', '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
07:48:05 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
07:48:05 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
07:48:05 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
07:48:05 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
07:48:05 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
07:48:05 INFO - 'COMPUTERNAME': 'T-W732-IX-034',
07:48:05 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
07:48:05 INFO - 'CYGWINBASE': 'C:\\cygwin',
07:48:05 INFO - 'DCLOCATION': 'SCL3',
07:48:05 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
07:48:05 INFO - 'FP_NO_HOST_CHECK': 'NO',
07:48:05 INFO - 'HOMEDRIVE': 'C:',
07:48:05 INFO - 'HOMEPATH': '\\Users\\cltbld',
07:48:05 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
07:48:05 INFO - 'KTS_VERSION': '1.19c',
07:48:05 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
07:48:05 INFO - 'LOGONSERVER': '\\\\T-W732-IX-034',
07:48:05 INFO - 'MONDIR': 'C:\\Monitor_config\\',
07:48:05 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
07:48:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
07:48:05 INFO - 'MOZ_NO_REMOTE': '1',
07:48:05 INFO - 'NO_EM_RESTART': '1',
07:48:05 INFO - 'NUMBER_OF_PROCESSORS': '8',
07:48:05 INFO - 'OS': 'Windows_NT',
07:48:05 INFO - 'OURDRIVE': 'C:',
07:48:05 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;',
07:48:05 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
07:48:05 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
07:48:05 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
07:48:05 INFO - 'PROCESSOR_LEVEL': '6',
07:48:05 INFO - 'PROCESSOR_REVISION': '1e05',
07:48:05 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
07:48:05 INFO - 'PROGRAMFILES': 'C:\\Program Files',
07:48:05 INFO - 'PROMPT': '$P$G',
07:48:05 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
07:48:05 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
07:48:05 INFO - 'PUBLIC': 'C:\\Users\\Public',
07:48:05 INFO - 'PWD': 'C:\\slave\\test',
07:48:05 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
07:48:05 INFO - 'SLAVEDIR': 'C:\\slave\\',
07:48:05 INFO - 'SYSTEMDRIVE': 'C:',
07:48:05 INFO - 'SYSTEMROOT': 'C:\\windows',
07:48:05 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:48:05 INFO - 'TEST1': 'testie',
07:48:05 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:48:05 INFO - 'USERDOMAIN': 'T-W732-IX-034',
07:48:05 INFO - 'USERNAME': 'cltbld',
07:48:05 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
07:48:05 INFO - 'WINDIR': 'C:\\windows',
07:48:05 INFO - 'WINDOWS_TRACING_FLAGS': '3',
07:48:05 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
07:48:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
07:48:05 INFO - Ignoring indexes: https://pypi.python.org/simple/
07:48:05 INFO - Downloading/unpacking jsonschema==2.5.1
07:48:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
07:48:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
07:48:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl
07:48:06 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1)
07:48:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
07:48:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
07:48:06 INFO - Downloading functools32-3.2.3-2.tar.gz
07:48:06 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32
07:48:07 INFO - warning: no files found matching '*.txt'
07:48:07 INFO - no previously-included directories found matching 'build'
07:48:07 INFO - no previously-included directories found matching 'dist'
07:48:07 INFO - no previously-included directories found matching '.git*'
07:48:07 INFO - Installing collected packages: jsonschema, functools32
07:48:07 INFO - Running setup.py install for functools32
07:48:07 INFO - warning: no files found matching '*.txt'
07:48:07 INFO - no previously-included directories found matching 'build'
07:48:07 INFO - no previously-included directories found matching 'dist'
07:48:07 INFO - no previously-included directories found matching '.git*'
07:48:07 INFO - Successfully installed jsonschema functools32
07:48:07 INFO - Cleaning up...
07:48:08 INFO - Return code: 0
07:48:08 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv
07:48: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')]}
07:48:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
07:48: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')]}
07:48:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
07:48:08 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x018D8640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01912E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01852B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0192CE90>, '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 0x0122A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01998520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-034', 'USERDOMAIN': 'T-W732-IX-034', '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-034', '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
07:48:08 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in C:\slave\test\build
07:48:08 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2
07:48:08 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
07:48:08 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
07:48:08 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
07:48:08 INFO - 'COMPUTERNAME': 'T-W732-IX-034',
07:48:08 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
07:48:08 INFO - 'CYGWINBASE': 'C:\\cygwin',
07:48:08 INFO - 'DCLOCATION': 'SCL3',
07:48:08 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
07:48:08 INFO - 'FP_NO_HOST_CHECK': 'NO',
07:48:08 INFO - 'HOMEDRIVE': 'C:',
07:48:08 INFO - 'HOMEPATH': '\\Users\\cltbld',
07:48:08 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
07:48:08 INFO - 'KTS_VERSION': '1.19c',
07:48:08 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
07:48:08 INFO - 'LOGONSERVER': '\\\\T-W732-IX-034',
07:48:08 INFO - 'MONDIR': 'C:\\Monitor_config\\',
07:48:08 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
07:48:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
07:48:08 INFO - 'MOZ_NO_REMOTE': '1',
07:48:08 INFO - 'NO_EM_RESTART': '1',
07:48:08 INFO - 'NUMBER_OF_PROCESSORS': '8',
07:48:08 INFO - 'OS': 'Windows_NT',
07:48:08 INFO - 'OURDRIVE': 'C:',
07:48: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;',
07:48:08 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
07:48:08 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
07:48:08 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
07:48:08 INFO - 'PROCESSOR_LEVEL': '6',
07:48:08 INFO - 'PROCESSOR_REVISION': '1e05',
07:48:08 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
07:48:08 INFO - 'PROGRAMFILES': 'C:\\Program Files',
07:48:08 INFO - 'PROMPT': '$P$G',
07:48:08 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
07:48:08 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
07:48:08 INFO - 'PUBLIC': 'C:\\Users\\Public',
07:48:08 INFO - 'PWD': 'C:\\slave\\test',
07:48:08 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
07:48:08 INFO - 'SLAVEDIR': 'C:\\slave\\',
07:48:08 INFO - 'SYSTEMDRIVE': 'C:',
07:48:08 INFO - 'SYSTEMROOT': 'C:\\windows',
07:48:08 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:48:08 INFO - 'TEST1': 'testie',
07:48:08 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:48:08 INFO - 'USERDOMAIN': 'T-W732-IX-034',
07:48:08 INFO - 'USERNAME': 'cltbld',
07:48:08 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
07:48:08 INFO - 'WINDIR': 'C:\\windows',
07:48:08 INFO - 'WINDOWS_TRACING_FLAGS': '3',
07:48:08 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
07:48:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
07:48:08 INFO - Ignoring indexes: https://pypi.python.org/simple/
07:48:08 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages
07:48:08 INFO - Cleaning up...
07:48:08 INFO - Return code: 0
07:48:08 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv
07:48: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')]}
07:48:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
07:48: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')]}
07:48:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
07:48:08 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x018D8640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01912E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01852B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0192CE90>, '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 0x0122A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01998520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-034', 'USERDOMAIN': 'T-W732-IX-034', '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-034', '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
07:48:08 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in C:\slave\test\build
07:48:08 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4
07:48:08 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
07:48:08 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
07:48:08 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
07:48:08 INFO - 'COMPUTERNAME': 'T-W732-IX-034',
07:48:08 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
07:48:08 INFO - 'CYGWINBASE': 'C:\\cygwin',
07:48:08 INFO - 'DCLOCATION': 'SCL3',
07:48:08 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
07:48:08 INFO - 'FP_NO_HOST_CHECK': 'NO',
07:48:08 INFO - 'HOMEDRIVE': 'C:',
07:48:08 INFO - 'HOMEPATH': '\\Users\\cltbld',
07:48:08 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
07:48:08 INFO - 'KTS_VERSION': '1.19c',
07:48:08 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
07:48:08 INFO - 'LOGONSERVER': '\\\\T-W732-IX-034',
07:48:08 INFO - 'MONDIR': 'C:\\Monitor_config\\',
07:48:08 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
07:48:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
07:48:08 INFO - 'MOZ_NO_REMOTE': '1',
07:48:08 INFO - 'NO_EM_RESTART': '1',
07:48:08 INFO - 'NUMBER_OF_PROCESSORS': '8',
07:48:08 INFO - 'OS': 'Windows_NT',
07:48:08 INFO - 'OURDRIVE': 'C:',
07:48: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;',
07:48:08 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
07:48:08 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
07:48:08 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
07:48:08 INFO - 'PROCESSOR_LEVEL': '6',
07:48:08 INFO - 'PROCESSOR_REVISION': '1e05',
07:48:08 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
07:48:08 INFO - 'PROGRAMFILES': 'C:\\Program Files',
07:48:08 INFO - 'PROMPT': '$P$G',
07:48:08 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
07:48:08 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
07:48:08 INFO - 'PUBLIC': 'C:\\Users\\Public',
07:48:08 INFO - 'PWD': 'C:\\slave\\test',
07:48:08 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
07:48:08 INFO - 'SLAVEDIR': 'C:\\slave\\',
07:48:08 INFO - 'SYSTEMDRIVE': 'C:',
07:48:08 INFO - 'SYSTEMROOT': 'C:\\windows',
07:48:08 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:48:08 INFO - 'TEST1': 'testie',
07:48:08 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:48:08 INFO - 'USERDOMAIN': 'T-W732-IX-034',
07:48:08 INFO - 'USERNAME': 'cltbld',
07:48:08 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
07:48:08 INFO - 'WINDIR': 'C:\\windows',
07:48:08 INFO - 'WINDOWS_TRACING_FLAGS': '3',
07:48:08 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
07:48:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
07:48:08 INFO - Ignoring indexes: https://pypi.python.org/simple/
07:48:08 INFO - Downloading/unpacking blobuploader==1.2.4
07:48:08 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
07:48:08 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
07:48:09 INFO - Downloading blobuploader-1.2.4.tar.gz
07:48:09 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader
07:48:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4)
07:48:09 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
07:48:09 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
07:48:10 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests
07:48:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4)
07:48:10 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
07:48:10 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
07:48:10 INFO - Downloading docopt-0.6.1.tar.gz
07:48:10 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt
07:48:10 INFO - Installing collected packages: blobuploader, requests, docopt
07:48:10 INFO - Running setup.py install for blobuploader
07:48:10 INFO - Running setup.py install for requests
07:48:11 INFO - Running setup.py install for docopt
07:48:11 INFO - Successfully installed blobuploader requests docopt
07:48:11 INFO - Cleaning up...
07:48:13 INFO - Return code: 0
07:48:13 INFO - Installing None into virtualenv C:\slave\test\build/venv
07:48:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
07:48:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
07:48:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
07:48:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
07:48:13 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 0x018D8640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01912E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01852B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0192CE90>, '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 0x0122A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01998520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-034', 'USERDOMAIN': 'T-W732-IX-034', '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-034', '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
07:48:13 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
07:48:13 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
07:48:13 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
07:48:13 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
07:48:13 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
07:48:13 INFO - 'COMPUTERNAME': 'T-W732-IX-034',
07:48:13 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
07:48:13 INFO - 'CYGWINBASE': 'C:\\cygwin',
07:48:13 INFO - 'DCLOCATION': 'SCL3',
07:48:13 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
07:48:13 INFO - 'FP_NO_HOST_CHECK': 'NO',
07:48:13 INFO - 'HOMEDRIVE': 'C:',
07:48:13 INFO - 'HOMEPATH': '\\Users\\cltbld',
07:48:13 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
07:48:13 INFO - 'KTS_VERSION': '1.19c',
07:48:13 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
07:48:13 INFO - 'LOGONSERVER': '\\\\T-W732-IX-034',
07:48:13 INFO - 'MONDIR': 'C:\\Monitor_config\\',
07:48:13 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
07:48:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
07:48:13 INFO - 'MOZ_NO_REMOTE': '1',
07:48:13 INFO - 'NO_EM_RESTART': '1',
07:48:13 INFO - 'NUMBER_OF_PROCESSORS': '8',
07:48:13 INFO - 'OS': 'Windows_NT',
07:48:13 INFO - 'OURDRIVE': 'C:',
07:48:13 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
07:48:13 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
07:48:13 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
07:48:13 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
07:48:13 INFO - 'PROCESSOR_LEVEL': '6',
07:48:13 INFO - 'PROCESSOR_REVISION': '1e05',
07:48:13 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
07:48:13 INFO - 'PROGRAMFILES': 'C:\\Program Files',
07:48:13 INFO - 'PROMPT': '$P$G',
07:48:13 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
07:48:13 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
07:48:13 INFO - 'PUBLIC': 'C:\\Users\\Public',
07:48:13 INFO - 'PWD': 'C:\\slave\\test',
07:48:13 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
07:48:13 INFO - 'SLAVEDIR': 'C:\\slave\\',
07:48:13 INFO - 'SYSTEMDRIVE': 'C:',
07:48:13 INFO - 'SYSTEMROOT': 'C:\\windows',
07:48:13 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:48:13 INFO - 'TEST1': 'testie',
07:48:13 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:48:13 INFO - 'USERDOMAIN': 'T-W732-IX-034',
07:48:13 INFO - 'USERNAME': 'cltbld',
07:48:13 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
07:48:13 INFO - 'WINDIR': 'C:\\windows',
07:48:13 INFO - 'WINDOWS_TRACING_FLAGS': '3',
07:48:13 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
07:48:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
07:48:13 INFO - Ignoring indexes: https://pypi.python.org/simple/
07:48:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
07:48:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jv2ioq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
07:48:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
07:48:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-eelsiw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
07:48:14 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
07:48:14 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-g69gbu-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
07:48:14 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
07:48:14 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-f9emio-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
07:48:14 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
07:48:14 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wksnf1-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
07:48:14 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
07:48:14 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ee9a55-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
07:48:14 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
07:48:14 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xiuj11-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
07:48:14 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
07:48:14 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-oqzgpa-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
07:48:14 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
07:48:14 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cp7feo-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
07:48:14 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
07:48:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-6y3m19-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
07:48:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
07:48:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fxbrn0-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
07:48:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
07:48:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-v_s9ha-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
07:48:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
07:48:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-6gkrnc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
07:48:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
07:48:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-v20om0-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
07:48:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
07:48:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bdxcnh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
07:48:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
07:48:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ekqzjv-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
07:48:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
07:48:16 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-juai8n-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
07:48:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
07:48:16 INFO - Running setup.py install for manifestparser
07:48:16 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts
07:48:16 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts
07:48:16 INFO - Running setup.py install for mozcrash
07:48:17 INFO - Running setup.py install for mozdebug
07:48:17 INFO - Running setup.py install for mozdevice
07:48:17 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts
07:48:17 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts
07:48:17 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts
07:48:17 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts
07:48:17 INFO - Running setup.py install for mozfile
07:48:18 INFO - Running setup.py install for mozhttpd
07:48:18 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts
07:48:18 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts
07:48:18 INFO - Running setup.py install for mozinfo
07:48:18 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts
07:48:18 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts
07:48:19 INFO - Running setup.py install for mozInstall
07:48:19 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts
07:48:19 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts
07:48:19 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts
07:48:19 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts
07:48:19 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts
07:48:19 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts
07:48:19 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts
07:48:19 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts
07:48:19 INFO - Running setup.py install for mozleak
07:48:19 INFO - Running setup.py install for mozlog
07:48:20 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts
07:48:20 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts
07:48:20 INFO - Running setup.py install for moznetwork
07:48:20 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts
07:48:20 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts
07:48:21 INFO - Running setup.py install for mozprocess
07:48:21 INFO - Running setup.py install for mozprofile
07:48:21 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts
07:48:21 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts
07:48:21 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts
07:48:21 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts
07:48:21 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts
07:48:21 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts
07:48:22 INFO - Running setup.py install for mozrunner
07:48:22 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts
07:48:22 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts
07:48:23 INFO - Running setup.py install for mozscreenshot
07:48:23 INFO - Running setup.py install for moztest
07:48:23 INFO - Running setup.py install for mozversion
07:48:24 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts
07:48:24 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts
07:48:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion
07:48:24 INFO - Cleaning up...
07:48:24 INFO - Return code: 0
07:48:24 INFO - Installing None into virtualenv C:\slave\test\build/venv
07:48:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
07:48:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
07:48:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
07:48:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
07:48:24 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 0x018D8640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01912E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01852B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0192CE90>, '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 0x0122A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01998520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-034', 'USERDOMAIN': 'T-W732-IX-034', '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-034', '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
07:48:24 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
07:48:24 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
07:48:24 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
07:48:24 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
07:48:24 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
07:48:24 INFO - 'COMPUTERNAME': 'T-W732-IX-034',
07:48:24 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
07:48:24 INFO - 'CYGWINBASE': 'C:\\cygwin',
07:48:24 INFO - 'DCLOCATION': 'SCL3',
07:48:24 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
07:48:24 INFO - 'FP_NO_HOST_CHECK': 'NO',
07:48:24 INFO - 'HOMEDRIVE': 'C:',
07:48:24 INFO - 'HOMEPATH': '\\Users\\cltbld',
07:48:24 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
07:48:24 INFO - 'KTS_VERSION': '1.19c',
07:48:24 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
07:48:24 INFO - 'LOGONSERVER': '\\\\T-W732-IX-034',
07:48:24 INFO - 'MONDIR': 'C:\\Monitor_config\\',
07:48:24 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
07:48:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
07:48:24 INFO - 'MOZ_NO_REMOTE': '1',
07:48:24 INFO - 'NO_EM_RESTART': '1',
07:48:24 INFO - 'NUMBER_OF_PROCESSORS': '8',
07:48:24 INFO - 'OS': 'Windows_NT',
07:48:24 INFO - 'OURDRIVE': 'C:',
07:48:24 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
07:48:24 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
07:48:24 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
07:48:24 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
07:48:24 INFO - 'PROCESSOR_LEVEL': '6',
07:48:24 INFO - 'PROCESSOR_REVISION': '1e05',
07:48:24 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
07:48:24 INFO - 'PROGRAMFILES': 'C:\\Program Files',
07:48:24 INFO - 'PROMPT': '$P$G',
07:48:24 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
07:48:24 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
07:48:24 INFO - 'PUBLIC': 'C:\\Users\\Public',
07:48:24 INFO - 'PWD': 'C:\\slave\\test',
07:48:24 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
07:48:24 INFO - 'SLAVEDIR': 'C:\\slave\\',
07:48:24 INFO - 'SYSTEMDRIVE': 'C:',
07:48:24 INFO - 'SYSTEMROOT': 'C:\\windows',
07:48:24 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:48:24 INFO - 'TEST1': 'testie',
07:48:24 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:48:24 INFO - 'USERDOMAIN': 'T-W732-IX-034',
07:48:24 INFO - 'USERNAME': 'cltbld',
07:48:24 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
07:48:24 INFO - 'WINDIR': 'C:\\windows',
07:48:24 INFO - 'WINDOWS_TRACING_FLAGS': '3',
07:48:24 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
07:48:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
07:48:24 INFO - Ignoring indexes: https://pypi.python.org/simple/
07:48:24 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
07:48:24 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-peiozd-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
07:48:24 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))
07:48:24 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
07:48:24 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ys_j7y-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
07:48:24 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))
07:48:24 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
07:48:24 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jq_dec-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
07:48:25 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))
07:48:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
07:48:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ers8am-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
07:48:25 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))
07:48:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
07:48:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ehm0go-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
07:48:25 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))
07:48:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
07:48:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-tedb78-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
07:48:25 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))
07:48:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
07:48:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-urggkp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
07:48:25 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))
07:48:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
07:48:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fk4r3a-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
07:48:25 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))
07:48:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
07:48:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-z_3slf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
07:48:25 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))
07:48:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
07:48:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bk_fi4-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
07:48:26 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))
07:48:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
07:48:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jeltsw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
07:48:26 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))
07:48:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
07:48:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-oaw18g-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
07:48:26 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))
07:48:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
07:48:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ffw6bp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
07:48:26 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))
07:48:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
07:48:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-nv44w7-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
07:48:26 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))
07:48:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
07:48:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jkjnon-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
07:48:26 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))
07:48:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
07:48:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-9kxzpt-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
07:48:27 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))
07:48:27 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
07:48:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-0l1tp9-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
07:48:27 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))
07:48:27 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))
07:48:27 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))
07:48:27 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))
07:48:27 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))
07:48:27 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))
07:48:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10))
07:48:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
07:48:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
07:48:28 INFO - Downloading blessings-1.6.tar.gz
07:48:28 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings
07:48:28 INFO - Installing collected packages: blessings
07:48:28 INFO - Running setup.py install for blessings
07:48:28 INFO - Successfully installed blessings
07:48:28 INFO - Cleaning up...
07:48:31 INFO - Return code: 0
07:48:31 INFO - Done creating virtualenv C:\slave\test\build/venv.
07:48:31 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
07:48:31 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
07:48:32 INFO - Reading from file tmpfile_stdout
07:48:32 INFO - Using _rmtree_windows ...
07:48:32 INFO - Using _rmtree_windows ...
07:48:32 INFO - Current package versions:
07:48:32 INFO - blessings == 1.6
07:48:32 INFO - blobuploader == 1.2.4
07:48:32 INFO - docopt == 0.6.1
07:48:32 INFO - functools32 == 3.2.3-2
07:48:32 INFO - jsonschema == 2.5.1
07:48:32 INFO - manifestparser == 1.1
07:48:32 INFO - mozInstall == 1.12
07:48:32 INFO - mozcrash == 0.17
07:48:32 INFO - mozdebug == 0.1
07:48:32 INFO - mozdevice == 0.48
07:48:32 INFO - mozfile == 1.2
07:48:32 INFO - mozhttpd == 0.7
07:48:32 INFO - mozinfo == 0.9
07:48:32 INFO - mozleak == 0.1
07:48:32 INFO - mozlog == 3.4
07:48:32 INFO - moznetwork == 0.27
07:48:32 INFO - mozprocess == 0.23
07:48:32 INFO - mozprofile == 0.28
07:48:32 INFO - mozrunner == 6.12
07:48:32 INFO - mozscreenshot == 0.1
07:48:32 INFO - mozsystemmonitor == 0.3
07:48:32 INFO - moztest == 0.7
07:48:32 INFO - mozversion == 1.4
07:48:32 INFO - psutil == 3.1.1
07:48:32 INFO - requests == 1.2.3
07:48:32 INFO - Installing None into virtualenv C:\slave\test\build/venv
07:48:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
07:48:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
07:48:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
07:48:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
07:48:32 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 0x018D8640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01912E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01852B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0192CE90>, '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 0x0122A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01998520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-034', 'USERDOMAIN': 'T-W732-IX-034', '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-034', '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
07:48:32 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
07:48:32 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
07:48:32 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
07:48:32 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
07:48:32 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
07:48:32 INFO - 'COMPUTERNAME': 'T-W732-IX-034',
07:48:32 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
07:48:32 INFO - 'CYGWINBASE': 'C:\\cygwin',
07:48:32 INFO - 'DCLOCATION': 'SCL3',
07:48:32 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
07:48:32 INFO - 'FP_NO_HOST_CHECK': 'NO',
07:48:32 INFO - 'HOMEDRIVE': 'C:',
07:48:32 INFO - 'HOMEPATH': '\\Users\\cltbld',
07:48:32 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
07:48:32 INFO - 'KTS_VERSION': '1.19c',
07:48:32 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
07:48:32 INFO - 'LOGONSERVER': '\\\\T-W732-IX-034',
07:48:32 INFO - 'MONDIR': 'C:\\Monitor_config\\',
07:48:32 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
07:48:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
07:48:32 INFO - 'MOZ_NO_REMOTE': '1',
07:48:32 INFO - 'NO_EM_RESTART': '1',
07:48:32 INFO - 'NUMBER_OF_PROCESSORS': '8',
07:48:32 INFO - 'OS': 'Windows_NT',
07:48:32 INFO - 'OURDRIVE': 'C:',
07:48:32 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
07:48:32 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
07:48:32 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
07:48:32 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
07:48:32 INFO - 'PROCESSOR_LEVEL': '6',
07:48:32 INFO - 'PROCESSOR_REVISION': '1e05',
07:48:32 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
07:48:32 INFO - 'PROGRAMFILES': 'C:\\Program Files',
07:48:32 INFO - 'PROMPT': '$P$G',
07:48:32 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
07:48:32 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
07:48:32 INFO - 'PUBLIC': 'C:\\Users\\Public',
07:48:32 INFO - 'PWD': 'C:\\slave\\test',
07:48:32 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
07:48:32 INFO - 'SLAVEDIR': 'C:\\slave\\',
07:48:32 INFO - 'SYSTEMDRIVE': 'C:',
07:48:32 INFO - 'SYSTEMROOT': 'C:\\windows',
07:48:32 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:48:32 INFO - 'TEST1': 'testie',
07:48:32 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:48:32 INFO - 'USERDOMAIN': 'T-W732-IX-034',
07:48:32 INFO - 'USERNAME': 'cltbld',
07:48:32 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
07:48:32 INFO - 'WINDIR': 'C:\\windows',
07:48:32 INFO - 'WINDOWS_TRACING_FLAGS': '3',
07:48:32 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
07:48:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
07:48:32 INFO - Ignoring indexes: https://pypi.python.org/simple/
07:48:32 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))
07:48:32 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))
07:48:32 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))
07:48:32 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))
07:48:32 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))
07:48:32 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))
07:48:32 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))
07:48:32 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))
07:48:32 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))
07:48:32 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))
07:48:32 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))
07:48:32 INFO - Cleaning up...
07:48:32 INFO - Return code: 0
07:48:32 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv
07:48:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
07:48:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
07:48:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
07:48:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
07:48:32 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 0x018D8640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01912E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01852B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0192CE90>, '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 0x0122A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01998520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-034', 'USERDOMAIN': 'T-W732-IX-034', '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-034', '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
07:48:32 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
07:48:32 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
07:48:32 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
07:48:32 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
07:48:32 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
07:48:32 INFO - 'COMPUTERNAME': 'T-W732-IX-034',
07:48:32 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
07:48:32 INFO - 'CYGWINBASE': 'C:\\cygwin',
07:48:32 INFO - 'DCLOCATION': 'SCL3',
07:48:32 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
07:48:32 INFO - 'FP_NO_HOST_CHECK': 'NO',
07:48:32 INFO - 'HOMEDRIVE': 'C:',
07:48:32 INFO - 'HOMEPATH': '\\Users\\cltbld',
07:48:32 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
07:48:32 INFO - 'KTS_VERSION': '1.19c',
07:48:32 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
07:48:32 INFO - 'LOGONSERVER': '\\\\T-W732-IX-034',
07:48:32 INFO - 'MONDIR': 'C:\\Monitor_config\\',
07:48:32 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
07:48:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
07:48:32 INFO - 'MOZ_NO_REMOTE': '1',
07:48:32 INFO - 'NO_EM_RESTART': '1',
07:48:32 INFO - 'NUMBER_OF_PROCESSORS': '8',
07:48:32 INFO - 'OS': 'Windows_NT',
07:48:32 INFO - 'OURDRIVE': 'C:',
07:48:32 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
07:48:32 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
07:48:32 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
07:48:32 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
07:48:32 INFO - 'PROCESSOR_LEVEL': '6',
07:48:32 INFO - 'PROCESSOR_REVISION': '1e05',
07:48:32 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
07:48:32 INFO - 'PROGRAMFILES': 'C:\\Program Files',
07:48:32 INFO - 'PROMPT': '$P$G',
07:48:32 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
07:48:32 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
07:48:32 INFO - 'PUBLIC': 'C:\\Users\\Public',
07:48:32 INFO - 'PWD': 'C:\\slave\\test',
07:48:32 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
07:48:32 INFO - 'SLAVEDIR': 'C:\\slave\\',
07:48:32 INFO - 'SYSTEMDRIVE': 'C:',
07:48:32 INFO - 'SYSTEMROOT': 'C:\\windows',
07:48:32 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:48:32 INFO - 'TEST1': 'testie',
07:48:32 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:48:32 INFO - 'USERDOMAIN': 'T-W732-IX-034',
07:48:32 INFO - 'USERNAME': 'cltbld',
07:48:32 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
07:48:32 INFO - 'WINDIR': 'C:\\windows',
07:48:32 INFO - 'WINDOWS_TRACING_FLAGS': '3',
07:48:32 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
07:48:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
07:48:32 INFO - Ignoring indexes: https://pypi.python.org/simple/
07:48:32 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages
07:48:32 INFO - Cleaning up...
07:48:32 INFO - Return code: 0
07:48:32 INFO - Running post-action listener: _resource_record_post_action
07:48:32 INFO - Running post-action listener: _start_resource_monitoring
07:48:32 INFO - Starting resource monitoring.
07:48:32 INFO - [mozharness: 2016-10-20 14:48:32.941000Z] Finished create-virtualenv step (success)
07:48:32 INFO - [mozharness: 2016-10-20 14:48:32.941000Z] Running install step.
07:48:32 INFO - Running pre-action listener: _resource_record_pre_action
07:48:32 INFO - Running main action method: install
07:48:32 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
07:48:32 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
07:48:33 INFO - Reading from file tmpfile_stdout
07:48:33 INFO - Using _rmtree_windows ...
07:48:33 INFO - Using _rmtree_windows ...
07:48:33 INFO - Detecting whether we're running mozinstall >=1.0...
07:48:33 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h']
07:48:33 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h
07:48:33 INFO - Reading from file tmpfile_stdout
07:48:33 INFO - Output received:
07:48:33 INFO - Usage: mozinstall-script.py [options] installer
07:48:33 INFO - Options:
07:48:33 INFO - -h, --help show this help message and exit
07:48:33 INFO - -d DEST, --destination=DEST
07:48:33 INFO - Directory to install application into. [default:
07:48:33 INFO - "C:\slave\test"]
07:48:33 INFO - --app=APP Application being installed. [default: firefox]
07:48:33 INFO - Using _rmtree_windows ...
07:48:33 INFO - Using _rmtree_windows ...
07:48:33 INFO - mkdir: C:\slave\test\build\application
07:48:33 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']
07:48:33 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
07:48:35 INFO - Reading from file tmpfile_stdout
07:48:35 INFO - Output received:
07:48:35 INFO - C:\slave\test\build\application\firefox\firefox.exe
07:48:35 INFO - Using _rmtree_windows ...
07:48:35 INFO - Using _rmtree_windows ...
07:48:35 INFO - Running post-action listener: _resource_record_post_action
07:48:35 INFO - [mozharness: 2016-10-20 14:48:35.571000Z] Finished install step (success)
07:48:35 INFO - [mozharness: 2016-10-20 14:48:35.571000Z] Running run-tests step.
07:48:35 INFO - Running pre-action listener: _resource_record_pre_action
07:48:35 INFO - Running main action method: run_tests
07:48:35 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version']
07:48:35 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version
07:48:35 INFO - Python 2.7.3
07:48:35 INFO - Return code: 0
07:48:35 INFO - grabbing minidump binary from tooltool
07:48:35 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')]}
07:48:35 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 0x0192CE90>, '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 0x0122A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01998520>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1
07:48:35 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
07:48:35 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
07:48:35 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
07:48:35 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'...
07:48:37 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmpwq9ivs
07:48:37 INFO - INFO - File integrity verified, renaming tmpwq9ivs to win32-minidump_stackwalk.exe
07:48:37 INFO - Return code: 0
07:48:37 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755
07:48:37 INFO - mkdir: C:\slave\test\build\blobber_upload_dir
07:48:37 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir
07:48:37 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe
07:48:37 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir
07:48:37 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/1476959407/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-034', '--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
07:48:37 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/1476959407/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-034 --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
07:48:37 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
07:48:37 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
07:48:37 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
07:48:37 INFO - 'COMPUTERNAME': 'T-W732-IX-034',
07:48:37 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
07:48:37 INFO - 'CYGWINBASE': 'C:\\cygwin',
07:48:37 INFO - 'DCLOCATION': 'SCL3',
07:48:37 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
07:48:37 INFO - 'FP_NO_HOST_CHECK': 'NO',
07:48:37 INFO - 'HOMEDRIVE': 'C:',
07:48:37 INFO - 'HOMEPATH': '\\Users\\cltbld',
07:48:37 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
07:48:37 INFO - 'KTS_VERSION': '1.19c',
07:48:37 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
07:48:37 INFO - 'LOGONSERVER': '\\\\T-W732-IX-034',
07:48:37 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir',
07:48:37 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe',
07:48:37 INFO - 'MONDIR': 'C:\\Monitor_config\\',
07:48:37 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
07:48:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
07:48:37 INFO - 'MOZ_NO_REMOTE': '1',
07:48:37 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir',
07:48:37 INFO - 'NO_EM_RESTART': '1',
07:48:37 INFO - 'NUMBER_OF_PROCESSORS': '8',
07:48:37 INFO - 'OS': 'Windows_NT',
07:48:37 INFO - 'OURDRIVE': 'C:',
07:48:37 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
07:48:37 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
07:48:37 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
07:48:37 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
07:48:37 INFO - 'PROCESSOR_LEVEL': '6',
07:48:37 INFO - 'PROCESSOR_REVISION': '1e05',
07:48:37 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
07:48:37 INFO - 'PROGRAMFILES': 'C:\\Program Files',
07:48:37 INFO - 'PROMPT': '$P$G',
07:48:37 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
07:48:37 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
07:48:37 INFO - 'PUBLIC': 'C:\\Users\\Public',
07:48:37 INFO - 'PWD': 'C:\\slave\\test',
07:48:37 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos',
07:48:37 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
07:48:37 INFO - 'SLAVEDIR': 'C:\\slave\\',
07:48:37 INFO - 'SYSTEMDRIVE': 'C:',
07:48:37 INFO - 'SYSTEMROOT': 'C:\\windows',
07:48:37 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:48:37 INFO - 'TEST1': 'testie',
07:48:37 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:48:37 INFO - 'USERDOMAIN': 'T-W732-IX-034',
07:48:37 INFO - 'USERNAME': 'cltbld',
07:48:37 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
07:48:37 INFO - 'WINDIR': 'C:\\windows',
07:48:37 INFO - 'WINDOWS_TRACING_FLAGS': '3',
07:48:37 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
07:48:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
07:48:37 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/1476959407/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-034', '--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
07:48:38 INFO - mozversion application_buildid: 20161020033007
07:48:38 INFO - mozversion application_changeset: 99a239e1866a57f987b08dad796528e4ea30e622
07:48:38 INFO - mozversion application_display_name: Nightly
07:48:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
07:48:38 INFO - mozversion application_name: Firefox
07:48:38 INFO - mozversion application_remotingname: firefox
07:48:38 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team
07:48:38 INFO - mozversion application_vendor: Mozilla
07:48:38 INFO - mozversion application_version: 52.0a1
07:48:38 INFO - mozversion platform_buildid: 20161020033007
07:48:38 INFO - mozversion platform_changeset: 99a239e1866a57f987b08dad796528e4ea30e622
07:48:38 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team
07:48:38 INFO - mozversion platform_version: 52.0a1
07:48:38 INFO - using testdate: 1476974918
07:48:38 INFO - actual date: 1476974918
07:48:38 INFO - starting webserver on 'localhost:49591'
07:48:38 INFO - SUITE-START | Running 2 tests
07:48:38 INFO - TEST-START | dromaeo_css
07:48:38 INFO - operating with platform_type : w7_
07:48:38 INFO - Initialising browser for dromaeo_css test...
07:48:38 INFO - TEST-INFO | started process 1884 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmp91wanz\profile http://localhost:49591/getInfo.html)
07:48:47 INFO - PROCESS | 1884 | __metrics Screen width/height:1600/1200
07:48:47 INFO - PROCESS | 1884 | colorDepth:24
07:48:47 INFO - PROCESS | 1884 | Browser inner width/height: 1010/674
07:48:47 INFO - PROCESS | 1884 | __metrics
07:48:48 INFO - PROCESS | 1884 | 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:48:48 INFO - PROCESS | 1884 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
07:48:49 INFO - TEST-INFO | 1884: exit 0
07:48:49 INFO - Browser initialized.
07:48:49 INFO - Running cycle 1/1 for dromaeo_css test...
07:48:49 INFO - TEST-INFO | started process 444 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmp91wanz\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1)
07:48:49 INFO - PROCESS | 444 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0
07:49:00 INFO - PROCESS | 444 | RSS: Main: 123244544
07:49:00 INFO - PROCESS | 444 |
07:49:51 INFO - PROCESS | 444 | 1476974991792 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:52:03 INFO - PROCESS | 444 | Cycle 1(1): loaded http://localhost:49591/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49591/tests/dromaeo/cssquery-ext.html)
07:52:05 INFO - PROCESS | 444 | RSS: Main: 292634624
07:52:05 INFO - PROCESS | 444 |
07:55:08 INFO - PROCESS | 444 | Cycle 1(1): loaded http://localhost:49591/tests/dromaeo/cssquery-ext.html (next: http://localhost:49591/tests/dromaeo/cssquery-jquery.html)
07:55:08 INFO - PROCESS | 444 | RSS: Main: 200077312
07:55:08 INFO - PROCESS | 444 |
07:58:10 INFO - PROCESS | 444 | Cycle 1(1): loaded http://localhost:49591/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49591/tests/dromaeo/cssquery-mootools.html)
07:58:11 INFO - PROCESS | 444 | RSS: Main: 145457152
07:58:11 INFO - PROCESS | 444 |
08:01:13 INFO - PROCESS | 444 | Cycle 1(1): loaded http://localhost:49591/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49591/tests/dromaeo/cssquery-prototype.html)
08:01:14 INFO - PROCESS | 444 | RSS: Main: 147931136
08:01:14 INFO - PROCESS | 444 |
08:04:16 INFO - PROCESS | 444 | Cycle 1(1): loaded http://localhost:49591/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49591/tests/dromaeo/cssquery-yui.html)
08:04:17 INFO - PROCESS | 444 | RSS: Main: 134787072
08:04:17 INFO - PROCESS | 444 |
08:07:19 INFO - PROCESS | 444 | Cycle 1(1): loaded http://localhost:49591/tests/dromaeo/cssquery-yui.html (next: http://localhost:49591/tests/dromaeo/cssquery-dojo.html)
08:07:20 INFO - PROCESS | 444 | RSS: Main: 183386112
08:07:20 INFO - PROCESS | 444 |
08:07:20 INFO - PROCESS | 444 | __start_tp_report
08:07:20 INFO - PROCESS | 444 | _x_x_mozilla_page_load
08:07:20 INFO - PROCESS | 444 | _x_x_mozilla_page_load_details
08:07:20 INFO - PROCESS | 444 | |i|pagename|runs|
08:07:20 INFO - PROCESS | 444 | |0;dojo.html;2728.7407696268856;2734.6171535984968;2758.7655049320806;2759.9862000689996;2778;4728.054389122172;4732.810687572497;4788.42538895333;4808.447028591713;4833.540813622709;4576.885577860547;4580.312953057043;4626.421697287839;4697.224957881954;4709.811607535703;4170.165966806636;4192.413062171283;4220.662347012231;4234.788260586974;4244.72409293395;4113.280175969208;4115.897102572445;4137.27597670408;4141.855035073773;4165.416841642157;4067.1865626874596;4113.095119073799;4170.895727606804;4184.560621134783;4232.449781528397;4053.858114965962;4125.690573207006;4127.864837169773;4193.727407718488;4217.051163488221;4103.958960410388;4138.234426631083;4157.854475093387;4222.197782421361;4238.025254191553;4674.953250467486;4746.477887432379;4749.548792864673;4760.452547956995;4771.498992605796;4903.043906438246;4937.358143441365;4944.060628480577;4955.4796746341835;5015.423226328946;4960.925586116211;5027.673201241908;5047.217681259411;5051.318072060261;5111.361079865017;4578.221702310615;4578.313253012042;4600.171969045604;4690.0854333405;4743.122522333345;5048.5203905628905;5098.949010509921;5177.275181474596;5192.532672059458;5203.401608814959;4861.465238823726;4888.7311197884455;4890.779914903838;4894.143524883202;4927.630427717864;4860.8298709545;4865.513448655106;4989.376327959005;4998.150314446553;4998.875028124253;4841.757912104381;4971.552560269593;4975.179095449283;4980.7011579304635;4983.177775666974;4919.803207871717;4955.752212389366;4984.77568509418;4990.17662085759;5003.449620541737;3340.732741380684;3381.932361352759;3388.119089036819;3392.5759280089987;3397.524346591479;2970.0102600354453;3071.4932036214223;3087.996401169584;3094.5512900629283;3111;4615.376924115269;4667.159911215946;4731.124741922756;4758.809647614126;4770.522947705202;4821.132196204717;4979.178435558165;4994.200927851526;5007.599392048627;5046.318746969186;4519.163954668397;4618.49196588375;4674.766261686902;4747.454042785055;4753.904921901542;4087.529934057562;4097.09863829957;4172.937405938914;4230.259704551691;4231.407602935591;5015.1223535880345;5050.318207042076;5062.645614806928;5108.821191258288;5244.527992480619;4852.75736213195;4862.6839255448285;4995.075910956485;4998.300237966615;5013.799448022039;4972.8508144755715;5012.448630650552;5085.7457127144235;5109.412417572027;5115.079285728604;4846.127697014571;4876.90246195067;4878.365812444359;4889.486603906538;4904.411470623548;4952.182889823139;4987.8753031173055;4997.875053123701;4999.625028122978;5009.749512524432;4669.9492614161545;4876.024795041077;4902.044101400335;4919.114559379203;4929.482404347492;4777.737224452689;4874.683145595525;4900.975495122501;4922.483139270325;4928.67963582366;4782.258749893772;4813.446453657734;4891.902161956812;4900.411950565955;4911;4849.296851956376;4872.43966943806;4877.975610121926;4996.175631020841;5001.274815151855;4616.653750968758;4693.108309421199;4824.059308434821;4839.733814640229;4845.200541910545
08:07:20 INFO - PROCESS | 444 | |1;ext.html;506.63775400588577;519.6570263626005;534.5750128647726;553.9972300138498;557.670974125266;37963.43054854176;38744.41883371748;38810.611893881054;38966.22067558647;41008.17983640333;39941.201175976465;40011.599884001225;40102.79948600253;40189;40327.79836100823;6717.966410167966;6902.930970690279;7811.531308121531;7934.523928564276;8118.837623247531;6908.412784913288;9829.508524573743;10011.199104071657;10034.498275086253;10310.793784124313;9911.900880991207;10016.348937319088;10140.594376224943;10175.643852465166;10189.184865210802;6872.931270687279;10108.393496390167;10149.644762433316;10198.235132365066;10261.538230779559;6930.20302665195;9619.855702164474;9972.052654997764;9979.80040399188;10201.795964080751;37375.62624373749;37566;37703.81148094242;37710;37723.81138094292;2827.985860070707;2894.7105289471097;2915.1546051644996;3224.9032529024166;3321.933561328784;28429.715702842914;29257.707422925923;30014.249643758863;30044.8497757512;30865.074047778602;3409.9488507672404;3467.7052450541732;3489;3530.0998245447504;3533.593636731783;317843;319004;319102;319404;319543;47930.281045784344;48470.5152948466;49073;50029.74985125051;50071.499285006685;27831.86084069607;28517.429651406856;28730.856345718137;52499.21251181235;52648.47351526513;13721.10812797185;13828.446862125402;13857.514986975408;13864.514741983981;13879.375428105759;9363.719088427359;9516.095970882756;10209.234307426972;10480.423576703355;10573.788524229472;16224;16301.429449969195;16613.33866613329;16657.91671041661;16994.32022719102;6375.627827094164;6432.871342573122;6444.226692796894;6447.774327898501;6451.774187903401;700.471144286071;785.8231897823058;786.1823703348454;853.3471894001094;921.074320308086;26313.76333011677;26809.597856032175;27438.314042148708;27511.58732619012;27548.586771198447;813.3696385301439;834.3075247544524;836.0844874862016;844.8437039147777;934.6074648647584;13798.724025519434;14045.69791869384;14332.856671433361;14482.203478808557;14615.561533154023;502987;510322;510597;510674;511037;46190.30714539284;47673;48342.27486587704;48518;49109.75445122751;963.2342287881152;996.3075662414552;1045.2369770067894;1078.3314345105928;1098.5990113608475;27123.72876271212;27203.183904482896;27448.862755686092;28031.159065228076;28202.71797282001;43971.12057758891;47650.285245720646;47814;48003;48007.75996119997;467636;470600;472079;476738;477133;22484.213052543084;22819.979604486987;23234.1868034618;24379.074511058785;24430.755692442846;40135.59864401319;40351.59648403478;41199.794001029804;41229.587704122576;41284.38073428841;34664.82667586646;35170.47244291286;35973;36583.26833463369;36606.63393366139;33944.490832637035;34289.31421371608;35890.4616430759;36039.459408109426;37037.814810925775;33550.49674254839;34201.31597368088;34335.313293734485;34421.82789086038;34460.65539344575;30584.694153058186;31554;32265.677343226267;33145.33709325752;33515.32969340551;24306.87846560756;24850.751492485568;25195.11817086393;25376.619350709385;25559.616605750558
08:07:20 INFO - PROCESS | 444 | |2;jquery.html;278.16827685221193;303.3432618381205;309.20842642834344;312.7794904592263;317;12494.687632809184;13240;13256.469741210363;13296.202227866335;13310.933445332772;12887.806682899763;13193.14244574102;13204.972483392561;13362.665933351647;16080.356785728556;4798.256270278095;5140.820071297505;5146.253793199984;5158.535731784139;5167.741612919339;4887.975560122194;4918.286848406997;5036.546710796046;5093.879346543773;5177.559907407875;5130.974345128269;5131.486851314858;5132;5132.743362831862;5146.279520867081;4942;5085.160948443502;5124.948750512484;5129.820456284031;5133.358330208743;4328.978355108205;4429.291313389842;4441;4480.260756975095;4492;18518.629627407376;19990.500237494034;20631.69052464214;20699;20718.274860379814;2006.6388050150968;2009.2867032203503;2470.938226544333;2523.9495210095697;2536.4293034067373;13160.934195328962;13293.13594616347;13329;13559.118657287343;13774.173549587056;1656.7680524726547;1709.7264437690035;1710.7262837945868;1751.9824801751947;1811.9275228990825;73726.262737373;76750.6162469184;77077;77354;78190.21809781886;8260;8530.019047809581;8570.528620925908;8665.566721663892;8938.68714594986;11040.227184097035;11097.278676885977;11461.426928653535;11530.077593792477;11546.653600392001;6865.794026179223;7044.964775176091;7065.646717664096;7121.109861267341;7168.319009694175;7112.857742845114;7129.964350178319;7221.350078492856;7235.059442272471;7246.311600397954;5524.806631767869;5568.9721551392795;5626.35296940857;5627.746751396197;5677.858053548694;1547.2573164880696;1567.3260497985975;1568.3412966553847;1570.3404570080593;1586.6112802363264;737.6533029476137;761.969521219145;784.5920121536883;795.8647030004913;836.1178956201145;16930.746038809426;17019;17076.914615426842;17428.564285892953;17795.644087118184;748.2666986353404;760.5474742528187;774.1073076328323;851.4550687559966;898.7438580004667;9494.145526902612;9813.16588090006;9898.554565044591;9983.700488985342;10097.293189476813;404266;405204;417843;418936;426607;78716;84441.15558844333;87638.56180719056;92307.5384623086;101151;789.7078081110026;814.9649944570363;815.1807433529265;816.3061397811813;817.9018517777905;15583.29875155598;15791.21043947775;15953.68092638164;16008;16552;41712.41163471955;42154.156916862106;43350;43776.56223437725;44350.55649443464;61559.38440615537;61576.69211653913;61859.07211391743;62289.377106228356;62438;7061.187963384071;7095.254998225112;7239.782806515813;7265.164506081868;7353.6690848912995;11732.592088949381;11766.176367654181;11816.40917954116;12101;12144.33206173669;9429.764255893657;9571.425714456876;9612.327137100616;9671.758206044624;9788.706338809847;6867.175938887365;6940.09778728762;7015.123109611299;7118.5017048806085;7128;7081.539699919489;7220.74727384539;7439.51643143194;7448.553086814808;7489.363404110712;11508.482118304864;11652.184347095623;11730.70673233176;11827.290362578273;11885.5840045598;8040.638171282423;10619.94690026545;11018.779624407627;11032.393218373067;11134.443277836237
08:07:20 INFO - PROCESS | 444 | |3;mootools.html;1060.777236780276;1069.9572017119315;1085.7122862441452;1230.224958276286;1382.7511048011354;6134.570580059398;6789.694463749131;6980.581165130089;7015;7018.929810701891;6995.370416662499;7025.543339682929;7040.155181378229;7048.823779405518;7075.0802395688615;1888.9244430222775;1891.3285783546849;1918.2614693343094;1918.5011896906835;1919.4721451600851;1774.3346245158066;1813.6191399805991;1837.9356722514715;1897.155765684271;1900.7433996410446;1896.7439395681613;1898.07943147574;1899.3067530351461;1900.914458849355;1906.9904650476728;1774.3700986149925;1793.5336812428732;1796.4520821149545;1797.2002458905793;1804.6120084181964;1781.9554511137196;1785.9464216073538;1787.5709829640923;1802;1812.0758413209226;6724.092247546617;6802.115724955774;6807.727690892408;6871.4502839772695;6970.756023539203;4024.1146947671464;4116.300228961084;4198.769067701275;4212.53662097169;4214.072903961123;2688.502627014009;2732.153032559893;2774.18161642314;2775.9861200693867;2777.805553611248;858.1032820702382;878.0999475537561;883.2536506651933;889.5552223888056;894.1907573645796;8840.734777956672;8919.152680495343;8972.371933964625;8978.685745998924;9004.864927026034;3397.371486275047;3421.726261899042;3471.861125555;3486.651334866493;3489.337025965109;7206.06321178254;7213.062301900824;7215.350618444365;7217.494775365674;7223.602701851449;3883.2815929053218;4032.8991775205855;4071.8371265149653;4095.2219078375015;4101.302778527657;3387.7628566000635;3498.492718555846;3673.3020726061964;3886.630770076838;3898.181381909774;2898.9275268118467;2965.3772707731187;2968.4656761782653;3011.9397612048074;3024.0323096609313;883.7569668341257;888.3293113699116;890.8173824365989;891.4473026723473;891.7859713668673;696.8258484453611;701.1270967645212;717.3149642091789;721.7077083781077;728.726727477196;4345.717528360647;4495.078508905666;4519.796609152596;4548;4558.954410455919;695.5583204664973;708.9432845372357;720.9134903811471;820.2003047029218;828.4201059258543;2938.250746059784;2999.7450216731386;3110.6422761382278;3166.7783255171917;3184.3153721949893;8031.397645176635;8134.308583770446;8277.130901255281;8292.668293268202;8347.04009038956;8117.431779775359;8292.668293268322;8348.540830254273;8361.749147525585;8362.080171181165;6502.187226596676;6583;6614.404703576702;6635.469162466991;6640.667966601747;4496.865094047183;4523.570260825217;4524.276115821453;4531.093781243699;4684.156851766715;7663.501872378278;7803.843923121392;7941.602919853869;7950.284474397332;7961.9203807963495;7704.268094531235;7976.7208147714555;8006.159353267823;8016.318612917968;8017.8797318039105;5233.502817232356;5375.408705042364;5454.645448045864;5565.693886836263;5589.469000444951;6027.969860150672;6161.815145545641;6222.439980401786;6309.621422714652;6626.5030122741955;5972.880542389041;6064.878702425838;6095.847603809941;6225.533085018551;6244.313125556095;6085.817425477243;6131.264248290234;6149.600275982047;6174.135621012972;6200.720967556561;5560.304961879765;5608.186812912187;5633.521150702236;5641.153826925994;5654.6889921054735;3727.9627203727614;3865.4974853268895;3993.980030099831;4072.6131017553284;4098.569650186807;3894.571597124371;3972.0665643574316;4049.0889549852227;4087.89780255496;4105.322621767375
08:07:20 INFO - PROCESS | 444 | |4;prototype.html;242.01620413021078;244.63060778224877;252.37159472912447;254.57613074231406;256.52286746651225;5050.292958985745;5147.279380886679;5148.819791307305;5163.81926632568;5164.664296820704;4882;4961.330220420242;5004.649674522776;5034.773435195416;5065.898682026357;3751;3758.2859256741267;3763.4919285896326;3763.73653844231;3772.302124107049;3598.5321908151905;3769.5288088988877;3769.962300377002;3771.3211621908044;3781.3382658034734;3525.206828463588;3684.9631503685023;3687.3731465650903;3688.465172549992;3690.8892733218045;3660.4692319613505;3680.650338217865;3723.497327860758;3724.404095344732;3738.45792360109;3177.9682203177904;3230.628477725045;3231.5637388952428;3234.3692979868933;3239.659835717263;4948.901021979576;4956.157453232959;4958.429780575244;4959.528844759742;4962.379702537183;3953.9011524712114;4246.5541118182455;4287.8851498610275;4313.374560688714;4381.233284175257;5201.427842937273;5246.160614301732;5279.498447647467;5288.709120998344;5290.021346050954;3372.325534893031;3374.5613070300706;3393.7454690898035;3421.760476766627;3477.1654802847133;5420.91868621971;5566.916496252559;5585.74864131111;5593.580481463907;5596.552275817925;3271.5910511186103;3464.6535346465657;3550.9112272192883;3552.5559305086863;3573.4461158519953;3477.1133360992785;3608.296382205445;3609.187932715118;3611.494390785292;3620.6741393274733;4057.3102572562734;4199.328107502785;4255.680823938217;4258.659307255412;4261.062566235423;5304.336957880265;5369.275147855068;5383.488568585978;5390;5392.784288628411;1237.4060450983397;1242.372601836067;1247.2329517346766;1249.188027781931;1252.1798222164498;751.443931490693;766.6320166320197;784.7841843493085;804.6137853830194;814.5031530766228;4817.566419022305;4901.460839307673;4950.727709975986;4965.652404331734;4971.328870602495;5284.776286465253;5384;5395.406505284415;5407.485903946901;5419.674819510763;4885.946283572767;4927.43334516528;4958.355413796184;4961.206207006934;4996.1007018736245;2831.207261966653;2844.559093340494;2878.9856050719613;2880.1071667783053;2908.5055540558155;5636.830895073154;5638.900414419231;5670.404607516151;5673.347565030074;5680.857978550486;5478.369987451414;5488.588355873246;5549.195366671891;5575.247341608912;5603.5237004854225;5466.753996070107;5669.149627555899;5669.433056694297;5670.5180059695385;5746.2529871116485;5479.589030822784;5553.500184983371;5582.8325150245555;5591;5591.490297619701;5469.699166545719;5481.752481482573;5492.670439773627;5503.477169668875;5518.91721624184;5688.402717714746;5742.971285143548;5755.453231942959;5761.539076873756;5793.275840519935;3403.387390269676;3578.856845726246;3582.229820588586;3583.3012562551094;3586.677199052098;5291.4443983382735;5339.813106541253;5476.644018138808;5478.863243335182;5490.588205884655;5241.266222728745;5251.52736253739;5300.3639563252655;5349.625526213128;5363.865903352447;5349.090654588651;5355.277037599874;5365.570754339721;5367.919481207707;5383.623146379716;5213.322268105122;5217.269582258411;5278.181881808326;5281.55106815925;5283.181106928432;5168.276441298296;5336.599755018312;5365.946340536545;5370.9731451342495;5392.191171324333;5285.312909321764;5289.947100528945;5302.522772950453;5305.416404195458;5311.150215965427
08:07:20 INFO - PROCESS | 444 | |5;yui.html;616.5468380740156;640.6348381422589;676.3675962974619;697.6581475077214;704.968276427561;14181.149131052143;14462.421503139874;15069.472568460105;15327.540173794803;15328;13460.798088028663;13471.393787279572;13556;13619.114757540752;13650.931745341284;616.2481772237862;628.7610707931003;633.2495992249177;633.2749002392258;633.4077637409014;501.6205435053603;512.4798329695343;607.1681795939569;617.8362733875504;619.9132121502993;631.3307893632741;635.529708016067;647.9870402591945;649.9740010399602;650.6974256970508;630.8832865919819;651.2185377546963;651.5243871973462;651.726274964516;652.2075678051167;383.2603076063197;388.8055972013993;462.5467042298533;470.36265859759874;470.83050101963266;12237.510499580007;12456.065795065315;12506.562270320586;12515.687107822287;12543;12533.869939432532;12627.747445051047;12760.80858787119;12855.82278164879;12994.740105197938;11961.162718609701;12507.93746031264;12745.122307429518;12799.168054076456;13033.739325213442;4195.832166713328;4211.852585159535;4225.450691410128;4226.197022565733;4368.257396242615;78868.60565697135;79969.20030799675;80604;81141.59429202817;82608;9975.45135017581;9975.902650708416;10121.987801219819;10267.127294179929;10341.86239513653;18693.626127477375;19024;19052.90473547651;19112.331068412816;19117.713234301496;6925.792226233221;6952.860942781116;7099.396551293198;7190.065291512175;7287.781366559012;3674.614165512636;3702.962970370316;3718.795466249328;3772.9245415091546;3863.285292220948;2671.22534465003;2738.6302849115114;2749.2439579115503;2788.498070347357;2794.203651959182;479.74333731453504;491.8868660208172;493.41283872191974;493.76546140582934;497.726161731378;581.8632621333983;598.5241732822417;615.3477314047125;637.2194062273678;666.7966270287512;8798.051297889446;9339.626414943463;9565.378250413702;9847.409155450696;9892.80214395708;626.1984659635674;675.1914113279902;677.1265068062243;679.7247114918466;682.1064405628543;4531.637469002472;4786.569208771228;4792.7603619819565;4800.783964721596;4916.139675556763;102944.92961678014;110623.44688276669;114128;114352.42823785827;115948;100249;103378.48310758549;104318.47840760747;104436;104800.47599761952;86611;87473;88899.55550222208;89441.55279223692;89601.55199224093;8306.54314012735;8356.832863342821;8441.451305665358;8690.522021288889;8874.04389591204;50963;52604.947901041;52729.945401091;52824;53736;71657.28342716715;74216.25783742094;76336.61831690806;76695.23304767103;78379.60810195912;10711.678649640524;10840.241183117107;11027.558897644005;11090.500927458123;11208.831867522156;12829.61511154667;12915;12986.805197921849;13085.08404411682;13548.390322435711;12388.318642474751;12395.75208495843;12441.315727635067;12462.750744985231;12591.24452532851;9651.806963860823;13297.135686180754;13455.259960701864;13467;13483.932580337036;13118.278494682503;13301.933490332873;13334.466621335037;13786.724265514835;13853.30733463343;11299.717507062389;11323.15076369292;11517.366544840113;12127.939360303142;12222.938885305872;10980.615678451215;10984.340939543654;11364.602238921598;11413.372264525531;11444.599439019594
08:07:20 INFO - PROCESS | 444 | __end_tp_report
08:07:20 INFO - PROCESS | 444 | __start_cc_report
08:07:20 INFO - PROCESS | 444 | _x_x_mozilla_cycle_collect,2390
08:07:20 INFO - PROCESS | 444 | __end_cc_report
08:07:20 INFO - PROCESS | 444 | __startTimestamp1476976040040__endTimestamp
08:07:20 INFO - PROCESS | 444 |
08:07:20 INFO - PROCESS | 444 | ------- Summary: start -------
08:07:20 INFO - PROCESS | 444 | Number of tests: 6
08:07:20 INFO - PROCESS | 444 |
08:07:20 INFO - PROCESS | 444 | [#0] dojo.html Cycles:165 Average:4589.65 Median:4833.54 stddev:577.31 (11.9%) stddev-sans-first:560.32
08:07:20 INFO - PROCESS | 444 | Values: 2728.7 2734.6 2758.8 2760.0 2778.0 4728.1 4732.8 4788.4 4808.4 4833.5 4576.9 4580.3 4626.4 4697.2 4709.8 4170.2 4192.4 4220.7 4234.8 4244.7 4113.3 4115.9 4137.3 4141.9 4165.4 4067.2 4113.1 4170.9 4184.6 4232.4 4053.9 4125.7 4127.9 4193.7 4217.1 4104.0 4138.2 4157.9 4222.2 4238.0 4675.0 4746.5 4749.5 4760.5 4771.5 4903.0 4937.4 4944.1 4955.5 5015.4 4960.9 5027.7 5047.2 5051.3 5111.4 4578.2 4578.3 4600.2 4690.1 4743.1 5048.5 5098.9 5177.3 5192.5 5203.4 4861.5 4888.7 4890.8 4894.1 4927.6 4860.8 4865.5 4989.4 4998.2 4998.9 4841.8 4971.6 4975.2 4980.7 4983.2 4919.8 4955.8 4984.8 4990.2 5003.4 3340.7 3381.9 3388.1 3392.6 3397.5 2970.0 3071.5 3088.0 3094.6 3111.0 4615.4 4667.2 4731.1 4758.8 4770.5 4821.1 4979.2 4994.2 5007.6 5046.3 4519.2 4618.5 4674.8 4747.5 4753.9 4087.5 4097.1 4172.9 4230.3 4231.4 5015.1 5050.3 5062.6 5108.8 5244.5 4852.8 4862.7 4995.1 4998.3 5013.8 4972.9 5012.4 5085.7 5109.4 5115.1 4846.1 4876.9 4878.4 4889.5 4904.4 4952.2 4987.9 4997.9 4999.6 5009.7 4669.9 4876.0 4902.0 4919.1 4929.5 4777.7 4874.7 4901.0 4922.5 4928.7 4782.3 4813.4 4891.9 4900.4 4911.0 4849.3 4872.4 4878.0 4996.2 5001.3 4616.7 4693.1 4824.1 4839.7 4845.2
08:07:20 INFO - PROCESS | 444 |
08:07:20 INFO - PROCESS | 444 | [#1] ext.html Cycles:180 Average:56338.01 Median:35931.73 stddev:117540.99 (327.1%) stddev-sans-first:117795.98
08:07:20 INFO - PROCESS | 444 | Values: 506.6 519.7 534.6 554.0 557.7 37963.4 38744.4 38810.6 38966.2 41008.2 39941.2 40011.6 40102.8 40189.0 40327.8 6718.0 6902.9 7811.5 7934.5 8118.8 6908.4 9829.5 10011.2 10034.5 10310.8 9911.9 10016.3 10140.6 10175.6 10189.2 6872.9 10108.4 10149.6 10198.2 10261.5 6930.2 9619.9 9972.1 9979.8 10201.8 37375.6 37566.0 37703.8 37710.0 37723.8 2828.0 2894.7 2915.2 3224.9 3321.9 28429.7 29257.7 30014.2 30044.8 30865.1 3409.9 3467.7 3489.0 3530.1 3533.6 317843.0 319004.0 319102.0 319404.0 319543.0 47930.3 48470.5 49073.0 50029.7 50071.5 27831.9 28517.4 28730.9 52499.2 52648.5 13721.1 13828.4 13857.5 13864.5 13879.4 9363.7 9516.1 10209.2 10480.4 10573.8 16224.0 16301.4 16613.3 16657.9 16994.3 6375.6 6432.9 6444.2 6447.8 6451.8 700.5 785.8 786.2 853.3 921.1 26313.8 26809.6 27438.3 27511.6 27548.6 813.4 834.3 836.1 844.8 934.6 13798.7 14045.7 14332.9 14482.2 14615.6 502987.0 510322.0 510597.0 510674.0 511037.0 46190.3 47673.0 48342.3 48518.0 49109.8 963.2 996.3 1045.2 1078.3 1098.6 27123.7 27203.2 27448.9 28031.2 28202.7 43971.1 47650.3 47814.0 48003.0 48007.8 467636.0 470600.0 472079.0 476738.0 477133.0 22484.2 22820.0 23234.2 24379.1 24430.8 40135.6 40351.6 41199.8 41229.6 41284.4 34664.8 35170.5 35973.0 36583.3 36606.6 33944.5 34289.3 35890.5 36039.5 37037.8 33550.5 34201.3 34335.3 34421.8 34460.7 30584.7 31554.0 32265.7 33145.3 33515.3 24306.9 24850.8 25195.1 25376.6 25559.6
08:07:20 INFO - PROCESS | 444 |
08:07:20 INFO - PROCESS | 444 | [#2] jquery.html Cycles:180 Average:25737.59 Median:5060.85 stddev:68974.65 (1362.9%) stddev-sans-first:69141.66
08:07:20 INFO - PROCESS | 444 | Values: 278.2 303.3 309.2 312.8 317.0 12494.7 13240.0 13256.5 13296.2 13310.9 12887.8 13193.1 13205.0 13362.7 16080.4 4798.3 5140.8 5146.3 5158.5 5167.7 4888.0 4918.3 5036.5 5093.9 5177.6 5131.0 5131.5 5132.0 5132.7 5146.3 4942.0 5085.2 5124.9 5129.8 5133.4 4329.0 4429.3 4441.0 4480.3 4492.0 18518.6 19990.5 20631.7 20699.0 20718.3 2006.6 2009.3 2470.9 2523.9 2536.4 13160.9 13293.1 13329.0 13559.1 13774.2 1656.8 1709.7 1710.7 1752.0 1811.9 73726.3 76750.6 77077.0 77354.0 78190.2 8260.0 8530.0 8570.5 8665.6 8938.7 11040.2 11097.3 11461.4 11530.1 11546.7 6865.8 7045.0 7065.6 7121.1 7168.3 7112.9 7130.0 7221.4 7235.1 7246.3 5524.8 5569.0 5626.4 5627.7 5677.9 1547.3 1567.3 1568.3 1570.3 1586.6 737.7 762.0 784.6 795.9 836.1 16930.7 17019.0 17076.9 17428.6 17795.6 748.3 760.5 774.1 851.5 898.7 9494.1 9813.2 9898.6 9983.7 10097.3 404266.0 405204.0 417843.0 418936.0 426607.0 78716.0 84441.2 87638.6 92307.5 101151.0 789.7 815.0 815.2 816.3 817.9 15583.3 15791.2 15953.7 16008.0 16552.0 41712.4 42154.2 43350.0 43776.6 44350.6 61559.4 61576.7 61859.1 62289.4 62438.0 7061.2 7095.3 7239.8 7265.2 7353.7 11732.6 11766.2 11816.4 12101.0 12144.3 9429.8 9571.4 9612.3 9671.8 9788.7 6867.2 6940.1 7015.1 7118.5 7128.0 7081.5 7220.7 7439.5 7448.6 7489.4 11508.5 11652.2 11730.7 11827.3 11885.6 8040.6 10619.9 11018.8 11032.4 11134.4
08:07:20 INFO - PROCESS | 444 |
08:07:20 INFO - PROCESS | 444 | [#3] mootools.html Cycles:180 Average:4447.64 Median:5598.83 stddev:2489.02 (44.5%) stddev-sans-first:2482.99
08:07:20 INFO - PROCESS | 444 | Values: 1060.8 1070.0 1085.7 1230.2 1382.8 6134.6 6789.7 6980.6 7015.0 7018.9 6995.4 7025.5 7040.2 7048.8 7075.1 1888.9 1891.3 1918.3 1918.5 1919.5 1774.3 1813.6 1837.9 1897.2 1900.7 1896.7 1898.1 1899.3 1900.9 1907.0 1774.4 1793.5 1796.5 1797.2 1804.6 1782.0 1785.9 1787.6 1802.0 1812.1 6724.1 6802.1 6807.7 6871.5 6970.8 4024.1 4116.3 4198.8 4212.5 4214.1 2688.5 2732.2 2774.2 2776.0 2777.8 858.1 878.1 883.3 889.6 894.2 8840.7 8919.2 8972.4 8978.7 9004.9 3397.4 3421.7 3471.9 3486.7 3489.3 7206.1 7213.1 7215.4 7217.5 7223.6 3883.3 4032.9 4071.8 4095.2 4101.3 3387.8 3498.5 3673.3 3886.6 3898.2 2898.9 2965.4 2968.5 3011.9 3024.0 883.8 888.3 890.8 891.4 891.8 696.8 701.1 717.3 721.7 728.7 4345.7 4495.1 4519.8 4548.0 4559.0 695.6 708.9 720.9 820.2 828.4 2938.3 2999.7 3110.6 3166.8 3184.3 8031.4 8134.3 8277.1 8292.7 8347.0 8117.4 8292.7 8348.5 8361.7 8362.1 6502.2 6583.0 6614.4 6635.5 6640.7 4496.9 4523.6 4524.3 4531.1 4684.2 7663.5 7803.8 7941.6 7950.3 7961.9 7704.3 7976.7 8006.2 8016.3 8017.9 5233.5 5375.4 5454.6 5565.7 5589.5 6028.0 6161.8 6222.4 6309.6 6626.5 5972.9 6064.9 6095.8 6225.5 6244.3 6085.8 6131.3 6149.6 6174.1 6200.7 5560.3 5608.2 5633.5 5641.2 5654.7 3728.0 3865.5 3994.0 4072.6 4098.6 3894.6 3972.1 4049.1 4087.9 4105.3
08:07:20 INFO - PROCESS | 444 |
08:07:20 INFO - PROCESS | 444 | [#4] prototype.html Cycles:180 Average:4383.71 Median:5106.59 stddev:1387.68 (27.2%) stddev-sans-first:1356.30
08:07:20 INFO - PROCESS | 444 | Values: 242.0 244.6 252.4 254.6 256.5 5050.3 5147.3 5148.8 5163.8 5164.7 4882.0 4961.3 5004.6 5034.8 5065.9 3751.0 3758.3 3763.5 3763.7 3772.3 3598.5 3769.5 3770.0 3771.3 3781.3 3525.2 3685.0 3687.4 3688.5 3690.9 3660.5 3680.7 3723.5 3724.4 3738.5 3178.0 3230.6 3231.6 3234.4 3239.7 4948.9 4956.2 4958.4 4959.5 4962.4 3953.9 4246.6 4287.9 4313.4 4381.2 5201.4 5246.2 5279.5 5288.7 5290.0 3372.3 3374.6 3393.7 3421.8 3477.2 5420.9 5566.9 5585.7 5593.6 5596.6 3271.6 3464.7 3550.9 3552.6 3573.4 3477.1 3608.3 3609.2 3611.5 3620.7 4057.3 4199.3 4255.7 4258.7 4261.1 5304.3 5369.3 5383.5 5390.0 5392.8 1237.4 1242.4 1247.2 1249.2 1252.2 751.4 766.6 784.8 804.6 814.5 4817.6 4901.5 4950.7 4965.7 4971.3 5284.8 5384.0 5395.4 5407.5 5419.7 4885.9 4927.4 4958.4 4961.2 4996.1 2831.2 2844.6 2879.0 2880.1 2908.5 5636.8 5638.9 5670.4 5673.3 5680.9 5478.4 5488.6 5549.2 5575.2 5603.5 5466.8 5669.1 5669.4 5670.5 5746.3 5479.6 5553.5 5582.8 5591.0 5591.5 5469.7 5481.8 5492.7 5503.5 5518.9 5688.4 5743.0 5755.5 5761.5 5793.3 3403.4 3578.9 3582.2 3583.3 3586.7 5291.4 5339.8 5476.6 5478.9 5490.6 5241.3 5251.5 5300.4 5349.6 5363.9 5349.1 5355.3 5365.6 5367.9 5383.6 5213.3 5217.3 5278.2 5281.6 5283.2 5168.3 5336.6 5365.9 5371.0 5392.2 5285.3 5289.9 5302.5 5305.4 5311.2
08:07:20 INFO - PROCESS | 444 |
08:07:20 INFO - PROCESS | 444 | [#5] yui.html Cycles:180 Average:20529.69 Median:4218.65 stddev:30576.02 (724.8%) stddev-sans-first:30625.23
08:07:20 INFO - PROCESS | 444 | Values: 616.5 640.6 676.4 697.7 705.0 14181.1 14462.4 15069.5 15327.5 15328.0 13460.8 13471.4 13556.0 13619.1 13650.9 616.2 628.8 633.2 633.3 633.4 501.6 512.5 607.2 617.8 619.9 631.3 635.5 648.0 650.0 650.7 630.9 651.2 651.5 651.7 652.2 383.3 388.8 462.5 470.4 470.8 12237.5 12456.1 12506.6 12515.7 12543.0 12533.9 12627.7 12760.8 12855.8 12994.7 11961.2 12507.9 12745.1 12799.2 13033.7 4195.8 4211.9 4225.5 4226.2 4368.3 78868.6 79969.2 80604.0 81141.6 82608.0 9975.5 9975.9 10122.0 10267.1 10341.9 18693.6 19024.0 19052.9 19112.3 19117.7 6925.8 6952.9 7099.4 7190.1 7287.8 3674.6 3703.0 3718.8 3772.9 3863.3 2671.2 2738.6 2749.2 2788.5 2794.2 479.7 491.9 493.4 493.8 497.7 581.9 598.5 615.3 637.2 666.8 8798.1 9339.6 9565.4 9847.4 9892.8 626.2 675.2 677.1 679.7 682.1 4531.6 4786.6 4792.8 4800.8 4916.1 102944.9 110623.4 114128.0 114352.4 115948.0 100249.0 103378.5 104318.5 104436.0 104800.5 86611.0 87473.0 88899.6 89441.6 89601.6 8306.5 8356.8 8441.5 8690.5 8874.0 50963.0 52604.9 52729.9 52824.0 53736.0 71657.3 74216.3 76336.6 76695.2 78379.6 10711.7 10840.2 11027.6 11090.5 11208.8 12829.6 12915.0 12986.8 13085.1 13548.4 12388.3 12395.8 12441.3 12462.8 12591.2 9651.8 13297.1 13455.3 13467.0 13483.9 13118.3 13301.9 13334.5 13786.7 13853.3 11299.7 11323.2 11517.4 12127.9 12222.9 10980.6 10984.3 11364.6 11413.4 11444.6
08:07:20 INFO - PROCESS | 444 | -------- Summary: end --------
08:07:20 INFO - PROCESS | 444 |
08:07:20 INFO - TEST-INFO | 444: exit 0
08:07:21 INFO - TEST-OK | dromaeo_css | took 1122559ms
08:07:21 INFO - TEST-START | kraken
08:07:21 INFO - operating with platform_type : w7_
08:07:21 INFO - Initialising browser for kraken test...
08:07:21 INFO - TEST-INFO | started process 2600 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpggiix7\profile http://localhost:49591/getInfo.html)
08:07:25 INFO - PROCESS | 2600 | __metrics Screen width/height:1600/1200
08:07:25 INFO - PROCESS | 2600 | colorDepth:24
08:07:25 INFO - PROCESS | 2600 | Browser inner width/height: 1010/674
08:07:25 INFO - PROCESS | 2600 | __metrics
08:07:26 INFO - PROCESS | 2600 | 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"
08:07:26 INFO - PROCESS | 2600 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
08:07:26 INFO - PROCESS | 2600 | console.error:
08:07:26 INFO - PROCESS | 2600 | Message: Error: Connection closed before committing the transaction.
08:07:26 INFO - PROCESS | 2600 | Stack:
08:07:26 INFO - PROCESS | 2600 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:937:23
08:07:26 INFO - PROCESS | 2600 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7
08:07:26 INFO - PROCESS | 2600 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11
08:07:26 INFO - PROCESS | 2600 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7
08:07:26 INFO - PROCESS | 2600 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7
08:07:26 INFO - PROCESS | 2600 | TaskImpl_run@resource://gre/modules/Task.jsm:327:15
08:07:26 INFO - PROCESS | 2600 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9
08:07:26 INFO - PROCESS | 2600 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15
08:07:26 INFO - PROCESS | 2600 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5
08:07:26 INFO - PROCESS | 2600 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29
08:07:26 INFO - PROCESS | 2600 | Barrier/this.client.addBlocker/promise13.1%
08:08:24 INFO - TinderboxPrint: I/O read bytes / time
228,845,056 / 3,198,050
08:08:24 INFO - TinderboxPrint: I/O write bytes / time
466,239,488 / 3,847,310
08:08:24 INFO - TinderboxPrint: CPU idle
8,281.9 (86.9%)
08:08:24 INFO - TinderboxPrint: CPU user
1,211.1 (12.7%)
08:08:24 INFO - install - Wall time: 3s; CPU: 11.0%; Read bytes: 0; Write bytes: 33482240; Read time: 0; Write time: 43810
08:08:24 INFO - run-tests - Wall time: 1189s; CPU: 13.0%; Read bytes: 228845056; Write bytes: 394766336; Read time: 3198050; Write time: 3457690
08:08:25 INFO - Running post-run listener: _upload_blobber_files
08:08:25 INFO - Blob upload gear active.
08:08:25 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir.
08:08:25 INFO - Files from C:\slave\test\build\blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com
08:08:25 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']
08:08:25 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
08:08:25 INFO - (blobuploader) - INFO - Open directory for files ...
08:08:25 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log ...
08:08:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
08:08:25 INFO - (blobuploader) - INFO - Uploading, attempt #1.
08:08:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded
08:08:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
08:08:26 INFO - (blobuploader) - INFO - Done attempting.
08:08:26 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log ...
08:08:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
08:08:26 INFO - (blobuploader) - INFO - Uploading, attempt #1.
08:08:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded
08:08:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
08:08:27 INFO - (blobuploader) - INFO - Done attempting.
08:08:27 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ...
08:08:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
08:08:27 INFO - (blobuploader) - INFO - Uploading, attempt #1.
08:08:35 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
08:08:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
08:08:35 INFO - (blobuploader) - INFO - Done attempting.
08:08:35 INFO - (blobuploader) - INFO - Iteration through files over.
08:08:35 INFO - Return code: 0
08:08:35 INFO - rmtree: C:\slave\test\build\uploaded_files.json
08:08:35 INFO - Using _rmtree_windows ...
08:08:35 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1
08:08:35 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ef097167d662519d79804ad51e437936d2b1ecedcdd6d65168c68bfcc499c8dcbba537c60736598e373226a68baf5dec9083f510c7f7831188fed3a88e3145ab", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e556ce444f050089e7fcdac70302e5e570ab86dc4a3c000dc8383d9a89124e64ad72d4ad0f9f0efa4bdc84841353bbabcac806542eefcbded4fdb3429d884ef1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f8a3dbe95ac944fdbda3c1c6b8b1ebafbc164ee4d734efff31b780507101dfda7dbe3d4396d8f7081b9c9f6978505c894dab63b15f7a83e4fddb245bb6a69367"}
08:08:35 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files
08:08:35 INFO - Writing to file C:\slave\test\properties\blobber_files
08:08:35 INFO - Contents:
08:08:35 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ef097167d662519d79804ad51e437936d2b1ecedcdd6d65168c68bfcc499c8dcbba537c60736598e373226a68baf5dec9083f510c7f7831188fed3a88e3145ab", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e556ce444f050089e7fcdac70302e5e570ab86dc4a3c000dc8383d9a89124e64ad72d4ad0f9f0efa4bdc84841353bbabcac806542eefcbded4fdb3429d884ef1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f8a3dbe95ac944fdbda3c1c6b8b1ebafbc164ee4d734efff31b780507101dfda7dbe3d4396d8f7081b9c9f6978505c894dab63b15f7a83e4fddb245bb6a69367"}
08:08:35 INFO - Running post-run listener: copy_logs_to_upload_dir
08:08:35 INFO - Copying logs to upload dir...
08:08:35 INFO - mkdir: C:\slave\test\build\upload\logs
08:08:35 INFO - Copying logs to upload dir...
08:08:35 INFO - Using _rmtree_windows ...
08:08:35 INFO - Using _rmtree_windows ...
08:08:35 INFO - Using _rmtree_windows ...
08:08:35 INFO - Using _rmtree_windows ...
08:08:35 INFO - Using _rmtree_windows ...
08:08:35 INFO - Using _rmtree_windows ...
08:08:35 INFO - Using _rmtree_windows ...
program finished with exit code 0
elapsedTime=1418.384000
========= master_lag: 2.86 =========
========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 41 secs) (at 2016-10-20 08:08:37.916944) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-20 08:08:37.927440) =========
'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-034
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-034
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-034
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/ef097167d662519d79804ad51e437936d2b1ecedcdd6d65168c68bfcc499c8dcbba537c60736598e373226a68baf5dec9083f510c7f7831188fed3a88e3145ab", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e556ce444f050089e7fcdac70302e5e570ab86dc4a3c000dc8383d9a89124e64ad72d4ad0f9f0efa4bdc84841353bbabcac806542eefcbded4fdb3429d884ef1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f8a3dbe95ac944fdbda3c1c6b8b1ebafbc164ee4d734efff31b780507101dfda7dbe3d4396d8f7081b9c9f6978505c894dab63b15f7a83e4fddb245bb6a69367"}
build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476959407/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/1476959407/firefox-52.0a1.en-US.win32.zip'
blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ef097167d662519d79804ad51e437936d2b1ecedcdd6d65168c68bfcc499c8dcbba537c60736598e373226a68baf5dec9083f510c7f7831188fed3a88e3145ab", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e556ce444f050089e7fcdac70302e5e570ab86dc4a3c000dc8383d9a89124e64ad72d4ad0f9f0efa4bdc84841353bbabcac806542eefcbded4fdb3429d884ef1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f8a3dbe95ac944fdbda3c1c6b8b1ebafbc164ee4d734efff31b780507101dfda7dbe3d4396d8f7081b9c9f6978505c894dab63b15f7a83e4fddb245bb6a69367"}'
========= master_lag: 0.01 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-20 08:08:38.042982) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 08:08:38.043317) =========
'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-034
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-034
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-034
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
program finished with exit code 0
elapsedTime=0.101000
========= master_lag: 0.01 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 08:08:38.153789) =========
========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-10-20 08:08:38.154092) =========
========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-10-20 08:08:38.625652) =========
========= Total master_lag: 6.16 =========