builder: fx-team_win7_ix_test-dromaeojs-pgo
slave: t-w732-ix-046
starttime: 1476972888.96
results: success (0)
buildid: 20161020033007
builduid: a27c517f0b1c4a26875e2a28b2202a36
revision: 99a239e1866a57f987b08dad796528e4ea30e622
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-20 07:14:48.959098) =========
master: http://buildbot-master126.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-20 07:14:48.959680) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-20 07:14:48.960017) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-20 07:14:49.116973) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-20 07:14:49.117864) =========
'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-046
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-046
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-046
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:14:50-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
=> `archiver_client.py'
Resolving hg.mozilla.org... 63.245.215.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% 15.54 MB/s
07:14:51 (15.54 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=1.116000
========= master_lag: 0.03 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-20 07:14:50.263960) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 14 secs) (at 2016-10-20 07:14:50.264392) =========
'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-046
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-046
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-046
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=14.101000
========= master_lag: 0.04 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 14 secs) (at 2016-10-20 07:15:04.402742) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-20 07:15:04.403164) =========
'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-046
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-046
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-046
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:15:05,961 truncating revision to first 12 chars
2016-10-20 07:15:05,961 Setting DEBUG logging.
2016-10-20 07:15:05,963 attempt 1/10
2016-10-20 07:15:05,963 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:15:06,470 unpacking tar archive at: fx-team-99a239e1866a/testing/mozharness/
2016-10-20 07:15:06,644 skipping symlink on windows: configs/single_locale/linux32.py
program finished with exit code 0
elapsedTime=1.542000
========= master_lag: 0.10 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-20 07:15:06.047272) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-20 07:15:06.047607) =========
script_repo_revision: 99a239e1866a57f987b08dad796528e4ea30e622
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-20 07:15:06.048011) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-20 07:15:06.048309) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-20 07:15:06.058578) =========
========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 14 secs) (at 2016-10-20 07:15:06.058862) =========
'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-046
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-046
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-046
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:15:07 INFO - MultiFileLogger online at 20161020 07:15:07 in C:\slave\test
07:15:07 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:15:07 INFO - Dumping config to C:\slave\test\logs\localconfig.json.
07:15:07 INFO - {'append_to_log': False,
07:15:07 INFO - 'base_work_dir': 'C:\\slave\\test',
07:15:07 INFO - 'blob_upload_branch': 'Fx-Team',
07:15:07 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt',
07:15:07 INFO - 'branch': 'Fx-Team',
07:15:07 INFO - 'buildbot_json_path': 'buildprops.json',
07:15:07 INFO - 'config_files': ('talos/windows_config.py',),
07:15:07 INFO - 'default_actions': ('clobber',
07:15:07 INFO - 'read-buildbot-config',
07:15:07 INFO - 'download-and-extract',
07:15:07 INFO - 'populate-webroot',
07:15:07 INFO - 'create-virtualenv',
07:15:07 INFO - 'install',
07:15:07 INFO - 'run-tests'),
07:15:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
07:15:07 INFO - 'download_minidump_stackwalk': True,
07:15:07 INFO - 'download_symbols': 'ondemand',
07:15:07 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python',
07:15:07 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'),
07:15:07 INFO - 'hg': 'c:/mozilla-build/hg/hg',
07:15:07 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python',
07:15:07 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'),
07:15:07 INFO - 'python': 'c:/mozilla-build/python27/python.exe',
07:15:07 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe',
07:15:07 INFO - 'C:/mozilla-build/tooltool.py'),
07:15:07 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe',
07:15:07 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')},
07:15:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
07:15:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
07:15:07 INFO - 'installer_path': 'installer.exe',
07:15:07 INFO - 'log_level': 'info',
07:15:07 INFO - 'log_name': 'talos',
07:15:07 INFO - 'log_to_console': True,
07:15:07 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe',
07:15:07 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe',
07:15:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest',
07:15:07 INFO - 'opt_config_files': (),
07:15:07 INFO - 'pip_index': False,
07:15:07 INFO - 'sps_profile': False,
07:15:07 INFO - 'sps_profile_interval': 0,
07:15:07 INFO - 'suite': 'dromaeojs',
07:15:07 INFO - 'system_bits': '32',
07:15:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
07:15:07 INFO - 'title': 'T-W732-IX-046',
07:15:07 INFO - 'use_talos_json': True,
07:15:07 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'),
07:15:07 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv',
07:15:07 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll',
07:15:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
07:15:07 INFO - 'work_dir': 'build'}
07:15:07 INFO - [mozharness: 2016-10-20 14:15:07.444000Z] Running clobber step.
07:15:07 INFO - Running pre-action listener: _resource_record_pre_action
07:15:07 INFO - Running main action method: clobber
07:15:07 INFO - rmtree: C:\slave\test\build
07:15:07 INFO - Using _rmtree_windows ...
07:15:07 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1
07:16:42 INFO - Running post-action listener: _resource_record_post_action
07:16:42 INFO - [mozharness: 2016-10-20 14:16:42.182000Z] Finished clobber step (success)
07:16:42 INFO - [mozharness: 2016-10-20 14:16:42.182000Z] Running read-buildbot-config step.
07:16:42 INFO - Running pre-action listener: _resource_record_pre_action
07:16:42 INFO - Running main action method: read_buildbot_config
07:16:42 INFO - Using buildbot properties:
07:16:42 INFO - {
07:16:42 INFO - "project": "",
07:16:42 INFO - "product": "firefox",
07:16:42 INFO - "script_repo_revision": "production",
07:16:42 INFO - "builddir": "fx-team_win7_ix_test-dromaeojs",
07:16:42 INFO - "repository": "",
07:16:42 INFO - "buildername": "Windows 7 32-bit fx-team pgo talos dromaeojs",
07:16:42 INFO - "buildid": "20161020033007",
07:16:42 INFO - "pgo_build": "True",
07:16:42 INFO - "basedir": "C:\\slave\\test",
07:16:42 INFO - "buildnumber": 35,
07:16:42 INFO - "slavename": "t-w732-ix-046",
07:16:42 INFO - "master": "http://buildbot-master126.bb.releng.scl3.mozilla.com:8201/",
07:16:42 INFO - "platform": "win7_ix",
07:16:42 INFO - "scheduler": "tests-fx-team-win32-pgo-talos",
07:16:42 INFO - "branch": "fx-team",
07:16:42 INFO - "repo_path": "integration/fx-team",
07:16:42 INFO - "revision": "99a239e1866a57f987b08dad796528e4ea30e622",
07:16:42 INFO - "stage_platform": "win32-pgo",
07:16:42 INFO - "builduid": "a27c517f0b1c4a26875e2a28b2202a36",
07:16:42 INFO - "slavebuilddir": "test"
07:16:42 INFO - }
07:16:42 INFO - Found installer url https://queue.taskcluster.net/v1/task/WAAtoKMpTeeonEirK7BiOQ/artifacts/public/build/firefox-52.0a1.en-US.win32.zip.
07:16:42 INFO - Running post-action listener: _resource_record_post_action
07:16:42 INFO - [mozharness: 2016-10-20 14:16:42.186000Z] Finished read-buildbot-config step (success)
07:16:42 INFO - [mozharness: 2016-10-20 14:16:42.186000Z] Running download-and-extract step.
07:16:42 INFO - Running pre-action listener: _resource_record_pre_action
07:16:42 INFO - Running main action method: download_and_extract
07:16:42 INFO - mkdir: C:\slave\test\build\tests
07:16:42 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:16:42 INFO - trying https://queue.taskcluster.net/v1/task/WAAtoKMpTeeonEirK7BiOQ/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json
07:16:42 INFO - Downloading https://queue.taskcluster.net/v1/task/WAAtoKMpTeeonEirK7BiOQ/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json to C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json
07:16:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WAAtoKMpTeeonEirK7BiOQ/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json', 'file_name': 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.test_packages.json'}, attempt #1
07:16:43 INFO - Downloaded 1218 bytes.
07:16:43 INFO - Reading from file C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json
07:16:43 INFO - Using the following test package requirements:
07:16:43 INFO - {u'common': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
07:16:43 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
07:16:43 INFO - u'firefox-52.0a1.en-US.win32.cppunittest.tests.zip'],
07:16:43 INFO - u'gtest': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
07:16:43 INFO - u'jittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
07:16:43 INFO - u'jsshell-win32.zip'],
07:16:43 INFO - u'mochitest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
07:16:43 INFO - u'firefox-52.0a1.en-US.win32.mochitest.tests.zip'],
07:16:43 INFO - u'mozbase': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
07:16:43 INFO - u'reftest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
07:16:43 INFO - u'firefox-52.0a1.en-US.win32.reftest.tests.zip'],
07:16:43 INFO - u'talos': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
07:16:43 INFO - u'firefox-52.0a1.en-US.win32.talos.tests.zip'],
07:16:43 INFO - u'web-platform': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
07:16:43 INFO - u'firefox-52.0a1.en-US.win32.web-platform.tests.zip'],
07:16:43 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
07:16:43 INFO - u'firefox-52.0a1.en-US.win32.xpcshell.tests.zip']}
07:16:43 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip'] for test suite category: common
07:16:43 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/WAAtoKMpTeeonEirK7BiOQ/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip
07:16:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WAAtoKMpTeeonEirK7BiOQ/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1
07:16:43 INFO - Fetch https://queue.taskcluster.net/v1/task/WAAtoKMpTeeonEirK7BiOQ/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory
07:16:43 INFO - Http code: 200
07:16:43 INFO - via: 1.1 441811a054e8d055b893175754efd0c3.cloudfront.net (CloudFront)
07:16:43 INFO - x-amz-cf-id: eijWAa7AKeE3sQlcthY9Dl_30RDHOCpQv3dlimZrBljwssrOocfZBQ==
07:16:43 INFO - x-amz-version-id: zKEK8SKvQHnIJ7.Trt9_t0qkNPxmsvC6
07:16:44 INFO - Expected file size: 17262980
07:16:44 INFO - Obtained file size: 17262980
07:17:12 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:17:12 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/WAAtoKMpTeeonEirK7BiOQ/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip
07:17:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WAAtoKMpTeeonEirK7BiOQ/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1
07:17:12 INFO - Fetch https://queue.taskcluster.net/v1/task/WAAtoKMpTeeonEirK7BiOQ/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory
07:17:12 INFO - Http code: 200
07:17:12 INFO - via: 1.1 c54d7f08e2f3dab1918454910cc8aad0.cloudfront.net (CloudFront)
07:17:12 INFO - x-amz-cf-id: kBbYvCrBekxl9Xx-V2cEAP9wJBP60YjR42AKtP1YEm19jV-1vo9GOg==
07:17:12 INFO - x-amz-version-id: zKEK8SKvQHnIJ7.Trt9_t0qkNPxmsvC6
07:17:13 INFO - Expected file size: 17262980
07:17:13 INFO - Obtained file size: 17262980
07:17:23 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/WAAtoKMpTeeonEirK7BiOQ/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip
07:17:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WAAtoKMpTeeonEirK7BiOQ/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip'}, attempt #1
07:17:23 INFO - Fetch https://queue.taskcluster.net/v1/task/WAAtoKMpTeeonEirK7BiOQ/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip into memory
07:17:23 INFO - Http code: 200
07:17:23 INFO - via: 1.1 5ed093c37d643652e278a198e0556a40.cloudfront.net (CloudFront)
07:17:23 INFO - x-amz-cf-id: sfd4LAr-9pmwgKtIJpogs8laeIsZXPkbn8iBzI4nXMP-6L_B4s4O9g==
07:17:23 INFO - x-amz-version-id: tDMSNfu3H2Q0o4C92u.rWHnaXj_c5vhF
07:17:23 INFO - Expected file size: 14409028
07:17:23 INFO - Obtained file size: 14409028
07:17: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:17:24 INFO - trying https://queue.taskcluster.net/v1/task/WAAtoKMpTeeonEirK7BiOQ/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
07:17:24 INFO - Downloading https://queue.taskcluster.net/v1/task/WAAtoKMpTeeonEirK7BiOQ/artifacts/public/build/firefox-52.0a1.en-US.win32.zip to C:\slave\test\build\firefox-52.0a1.en-US.win32.zip
07:17:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WAAtoKMpTeeonEirK7BiOQ/artifacts/public/build/firefox-52.0a1.en-US.win32.zip', 'file_name': 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.zip'}, attempt #1
07:17:27 INFO - Downloaded 54321943 bytes.
07:17:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WAAtoKMpTeeonEirK7BiOQ/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
07:17:27 INFO - mkdir: C:\slave\test\properties
07:17:27 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url
07:17:27 INFO - Writing to file C:\slave\test\properties\build_url
07:17:27 INFO - Contents:
07:17:27 INFO - build_url:https://queue.taskcluster.net/v1/task/WAAtoKMpTeeonEirK7BiOQ/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
07:17:28 INFO - Running post-action listener: _resource_record_post_action
07:17:28 INFO - Running post-action listener: set_extra_try_arguments
07:17:28 INFO - [mozharness: 2016-10-20 14:17:28.001000Z] Finished download-and-extract step (success)
07:17:28 INFO - [mozharness: 2016-10-20 14:17:28.001000Z] Running populate-webroot step.
07:17:28 INFO - Running pre-action listener: _resource_record_pre_action
07:17:28 INFO - Running main action method: populate_webroot
07:17:28 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']},
07:17:28 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']},
07:17:28 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome',
07:17:28 INFO - u'--tppagecycles',
07:17:28 INFO - u'7'],
07:17:28 INFO - u'tests': [u'tsvgm']}},
07:17:28 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'],
07:17:28 INFO - u'tests': [u'tresize', u'tcanvasmark']},
07:17:28 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']},
07:17:28 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'],
07:17:28 INFO - u'tests': [u'dromaeo_css', u'kraken']},
07:17:28 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']},
07:17:28 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
07:17:28 INFO - u'talos_options': [u'--disable-e10s'],
07:17:28 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
07:17:28 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
07:17:28 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
07:17:28 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
07:17:28 INFO - u'talos_options': [u'--disable-e10s'],
07:17:28 INFO - u'tests': [u'damp', u'tps']},
07:17:28 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
07:17:28 INFO - u'tests': [u'damp', u'tps']},
07:17:28 INFO - u'g3': {u'talos_options': [u'--disable-e10s'],
07:17:28 INFO - u'tests': [u'dromaeo_dom']},
07:17:28 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']},
07:17:28 INFO - u'g4': {u'talos_options': [u'--disable-e10s'],
07:17:28 INFO - u'tests': [u'basic_compositor_video']},
07:17:28 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']},
07:17:28 INFO - u'other': {u'talos_options': [u'--disable-e10s'],
07:17:28 INFO - u'tests': [u'a11yr',
07:17:28 INFO - u'ts_paint',
07:17:28 INFO - u'tpaint',
07:17:28 INFO - u'sessionrestore',
07:17:28 INFO - u'sessionrestore_no_auto_restore',
07:17:28 INFO - u'tabpaint']},
07:17:28 INFO - u'other-e10s': {u'tests': [u'a11yr',
07:17:28 INFO - u'ts_paint',
07:17:28 INFO - u'tpaint',
07:17:28 INFO - u'sessionrestore',
07:17:28 INFO - u'sessionrestore_no_auto_restore',
07:17:28 INFO - u'tabpaint']},
07:17:28 INFO - u'other-e10s_l64': {u'tests': [u'a11yr',
07:17:28 INFO - u'ts_paint',
07:17:28 INFO - u'tpaint',
07:17:28 INFO - u'sessionrestore',
07:17:28 INFO - u'sessionrestore_no_auto_restore',
07:17:28 INFO - u'tabpaint']},
07:17:28 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr',
07:17:28 INFO - u'ts_paint',
07:17:28 INFO - u'tpaint',
07:17:28 INFO - u'sessionrestore',
07:17:28 INFO - u'sessionrestore_no_auto_restore',
07:17:28 INFO - u'tabpaint']},
07:17:28 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'],
07:17:28 INFO - u'tests': [u'a11yr',
07:17:28 INFO - u'ts_paint',
07:17:28 INFO - u'tpaint',
07:17:28 INFO - u'sessionrestore',
07:17:28 INFO - u'sessionrestore_no_auto_restore',
07:17:28 INFO - u'tabpaint']},
07:17:28 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'],
07:17:28 INFO - u'tests': [u'a11yr',
07:17:28 INFO - u'ts_paint',
07:17:28 INFO - u'tpaint',
07:17:28 INFO - u'sessionrestore',
07:17:28 INFO - u'sessionrestore_no_auto_restore',
07:17:28 INFO - u'tabpaint']},
07:17:28 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'],
07:17:28 INFO - u'tests': [u'tsvgx',
07:17:28 INFO - u'tsvgr_opacity',
07:17:28 INFO - u'tart',
07:17:28 INFO - u'tscrollx',
07:17:28 INFO - u'cart']},
07:17:28 INFO - u'svgr-e10s': {u'tests': [u'tsvgx',
07:17:28 INFO - u'tsvgr_opacity',
07:17:28 INFO - u'tart',
07:17:28 INFO - u'tscrollx',
07:17:28 INFO - u'cart']},
07:17:28 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
07:17:28 INFO - u'talos_options': [u'--disable-e10s'],
07:17:28 INFO - u'tests': [u'tp5o']},
07:17:28 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
07:17:28 INFO - u'tests': [u'tp5o']},
07:17:28 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
07:17:28 INFO - u'talos_options': [u'--disable-e10s',
07:17:28 INFO - u'--xperf_path',
07:17:28 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
07:17:28 INFO - u'tests': [u'tp5n']},
07:17:28 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
07:17:28 INFO - u'talos_options': [u'--xperf_path',
07:17:28 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
07:17:28 INFO - u'tests': [u'tp5n']}},
07:17:28 INFO - u'talos.zip': {u'path': u'',
07:17:28 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}}
07:17:28 INFO - Running post-action listener: _resource_record_post_action
07:17:28 INFO - [mozharness: 2016-10-20 14:17:28.094000Z] Finished populate-webroot step (success)
07:17:28 INFO - [mozharness: 2016-10-20 14:17:28.094000Z] Running create-virtualenv step.
07:17:28 INFO - Running pre-action listener: _resource_record_pre_action
07:17:28 INFO - Running main action method: create_virtualenv
07:17:28 INFO - Creating virtualenv C:\slave\test\build/venv
07:17:28 INFO - mkdir: C:\slave\test\build\venv\Scripts
07:17:28 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll
07:17: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:17: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:17: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:17:31 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe
07:17:39 INFO - Installing distribute......................................................................................................................................................................................done.
07:17:40 INFO - Return code: 0
07:17:40 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version']
07:17:40 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version
07:17:41 INFO - Reading from file tmpfile_stdout
07:17:41 INFO - Output received:
07:17:41 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:17:41 INFO - Using _rmtree_windows ...
07:17:41 INFO - Using _rmtree_windows ...
07:17:41 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv
07:17:41 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:17:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
07:17:41 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:17:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
07:17:41 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 0x017C2640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01869E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0176B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01883E90>, '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 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01939520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-046', 'USERDOMAIN': 'T-W732-IX-046', '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-046', '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:17:41 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:17:41 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:17:41 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
07:17:41 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
07:17:41 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
07:17:41 INFO - 'COMPUTERNAME': 'T-W732-IX-046',
07:17:41 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
07:17:41 INFO - 'CYGWINBASE': 'C:\\cygwin',
07:17:41 INFO - 'DCLOCATION': 'SCL3',
07:17:41 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
07:17:41 INFO - 'FP_NO_HOST_CHECK': 'NO',
07:17:41 INFO - 'HOMEDRIVE': 'C:',
07:17:41 INFO - 'HOMEPATH': '\\Users\\cltbld',
07:17:41 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
07:17:41 INFO - 'KTS_VERSION': '1.19c',
07:17:41 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
07:17:41 INFO - 'LOGONSERVER': '\\\\T-W732-IX-046',
07:17:41 INFO - 'MONDIR': 'C:\\Monitor_config\\',
07:17:41 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
07:17:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
07:17:41 INFO - 'MOZ_NO_REMOTE': '1',
07:17:41 INFO - 'NO_EM_RESTART': '1',
07:17:41 INFO - 'NUMBER_OF_PROCESSORS': '8',
07:17:41 INFO - 'OS': 'Windows_NT',
07:17:41 INFO - 'OURDRIVE': 'C:',
07:17:41 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:17:41 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
07:17:41 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
07:17:41 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
07:17:41 INFO - 'PROCESSOR_LEVEL': '6',
07:17:41 INFO - 'PROCESSOR_REVISION': '1e05',
07:17:41 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
07:17:41 INFO - 'PROGRAMFILES': 'C:\\Program Files',
07:17:41 INFO - 'PROMPT': '$P$G',
07:17:41 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
07:17:41 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
07:17:41 INFO - 'PUBLIC': 'C:\\Users\\Public',
07:17:41 INFO - 'PWD': 'C:\\slave\\test',
07:17:41 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
07:17:41 INFO - 'SLAVEDIR': 'C:\\slave\\',
07:17:41 INFO - 'SYSTEMDRIVE': 'C:',
07:17:41 INFO - 'SYSTEMROOT': 'C:\\windows',
07:17:41 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:17:41 INFO - 'TEST1': 'testie',
07:17:41 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:17:41 INFO - 'USERDOMAIN': 'T-W732-IX-046',
07:17:41 INFO - 'USERNAME': 'cltbld',
07:17:41 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
07:17:41 INFO - 'WINDIR': 'C:\\windows',
07:17:41 INFO - 'WINDOWS_TRACING_FLAGS': '3',
07:17:41 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
07:17:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
07:17:41 INFO - Ignoring indexes: http://pypi.python.org/simple/
07:17:41 INFO - Downloading/unpacking pip>=1.5
07:17:46 INFO - Running setup.py egg_info for package pip
07:17:46 INFO - warning: no files found matching 'pip\cacert.pem'
07:17:46 INFO - warning: no files found matching '*.html' under directory 'docs'
07:17:46 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
07:17:46 INFO - no previously-included directories found matching 'docs\_build\_sources'
07:17:46 INFO - Installing collected packages: pip
07:17:46 INFO - Found existing installation: pip 0.8.2
07:17:46 INFO - Uninstalling pip:
07:17:46 INFO - Successfully uninstalled pip
07:17:46 INFO - Running setup.py install for pip
07:17:46 INFO - warning: no files found matching 'pip\cacert.pem'
07:17:46 INFO - warning: no files found matching '*.html' under directory 'docs'
07:17:46 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
07:17:46 INFO - no previously-included directories found matching 'docs\_build\_sources'
07:17:46 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts
07:17:46 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts
07:17:46 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts
07:17:46 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts
07:17:46 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts
07:17:46 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts
07:17:46 INFO - Successfully installed pip
07:17:46 INFO - Cleaning up...
07:17:46 INFO - Return code: 0
07:17:46 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv
07:17:46 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:17:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
07:17:46 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:17:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
07:17:46 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 0x017C2640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01869E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0176B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01883E90>, '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 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01939520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-046', 'USERDOMAIN': 'T-W732-IX-046', '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-046', '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:17:46 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:17:46 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:17:46 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
07:17:46 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
07:17:46 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
07:17:46 INFO - 'COMPUTERNAME': 'T-W732-IX-046',
07:17:46 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
07:17:46 INFO - 'CYGWINBASE': 'C:\\cygwin',
07:17:46 INFO - 'DCLOCATION': 'SCL3',
07:17:46 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
07:17:46 INFO - 'FP_NO_HOST_CHECK': 'NO',
07:17:46 INFO - 'HOMEDRIVE': 'C:',
07:17:46 INFO - 'HOMEPATH': '\\Users\\cltbld',
07:17:46 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
07:17:46 INFO - 'KTS_VERSION': '1.19c',
07:17:46 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
07:17:46 INFO - 'LOGONSERVER': '\\\\T-W732-IX-046',
07:17:46 INFO - 'MONDIR': 'C:\\Monitor_config\\',
07:17:46 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
07:17:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
07:17:46 INFO - 'MOZ_NO_REMOTE': '1',
07:17:46 INFO - 'NO_EM_RESTART': '1',
07:17:46 INFO - 'NUMBER_OF_PROCESSORS': '8',
07:17:46 INFO - 'OS': 'Windows_NT',
07:17:46 INFO - 'OURDRIVE': 'C:',
07:17:46 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:17:46 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
07:17:46 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
07:17:46 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
07:17:46 INFO - 'PROCESSOR_LEVEL': '6',
07:17:46 INFO - 'PROCESSOR_REVISION': '1e05',
07:17:46 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
07:17:46 INFO - 'PROGRAMFILES': 'C:\\Program Files',
07:17:46 INFO - 'PROMPT': '$P$G',
07:17:46 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
07:17:46 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
07:17:46 INFO - 'PUBLIC': 'C:\\Users\\Public',
07:17:46 INFO - 'PWD': 'C:\\slave\\test',
07:17:46 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
07:17:46 INFO - 'SLAVEDIR': 'C:\\slave\\',
07:17:46 INFO - 'SYSTEMDRIVE': 'C:',
07:17:46 INFO - 'SYSTEMROOT': 'C:\\windows',
07:17:46 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:17:46 INFO - 'TEST1': 'testie',
07:17:46 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:17:46 INFO - 'USERDOMAIN': 'T-W732-IX-046',
07:17:46 INFO - 'USERNAME': 'cltbld',
07:17:46 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
07:17:46 INFO - 'WINDIR': 'C:\\windows',
07:17:46 INFO - 'WINDOWS_TRACING_FLAGS': '3',
07:17:46 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
07:17:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
07:17:48 INFO - Ignoring indexes: https://pypi.python.org/simple/
07:17:48 INFO - Downloading/unpacking psutil>=3.1.1
07:17:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
07:17:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
07:17:49 INFO - Installing collected packages: psutil
07:17:49 INFO - Successfully installed psutil
07:17:49 INFO - Cleaning up...
07:17:49 INFO - Return code: 0
07:17:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv
07:17:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
07:17:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
07:17:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
07:17:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
07:17:49 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017C2640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01869E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0176B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01883E90>, '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 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01939520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-046', 'USERDOMAIN': 'T-W732-IX-046', '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-046', '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:17:49 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in C:\slave\test\build
07:17:49 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3
07:17:49 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
07:17:49 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
07:17:49 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
07:17:49 INFO - 'COMPUTERNAME': 'T-W732-IX-046',
07:17:49 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
07:17:49 INFO - 'CYGWINBASE': 'C:\\cygwin',
07:17:49 INFO - 'DCLOCATION': 'SCL3',
07:17:49 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
07:17:49 INFO - 'FP_NO_HOST_CHECK': 'NO',
07:17:49 INFO - 'HOMEDRIVE': 'C:',
07:17:49 INFO - 'HOMEPATH': '\\Users\\cltbld',
07:17:49 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
07:17:49 INFO - 'KTS_VERSION': '1.19c',
07:17:49 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
07:17:49 INFO - 'LOGONSERVER': '\\\\T-W732-IX-046',
07:17:49 INFO - 'MONDIR': 'C:\\Monitor_config\\',
07:17:49 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
07:17:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
07:17:49 INFO - 'MOZ_NO_REMOTE': '1',
07:17:49 INFO - 'NO_EM_RESTART': '1',
07:17:49 INFO - 'NUMBER_OF_PROCESSORS': '8',
07:17:49 INFO - 'OS': 'Windows_NT',
07:17:49 INFO - 'OURDRIVE': 'C:',
07:17:49 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
07:17:49 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
07:17:49 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
07:17:49 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
07:17:49 INFO - 'PROCESSOR_LEVEL': '6',
07:17:49 INFO - 'PROCESSOR_REVISION': '1e05',
07:17:49 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
07:17:49 INFO - 'PROGRAMFILES': 'C:\\Program Files',
07:17:49 INFO - 'PROMPT': '$P$G',
07:17:49 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
07:17:49 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
07:17:49 INFO - 'PUBLIC': 'C:\\Users\\Public',
07:17:49 INFO - 'PWD': 'C:\\slave\\test',
07:17:49 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
07:17:49 INFO - 'SLAVEDIR': 'C:\\slave\\',
07:17:49 INFO - 'SYSTEMDRIVE': 'C:',
07:17:49 INFO - 'SYSTEMROOT': 'C:\\windows',
07:17:49 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:17:49 INFO - 'TEST1': 'testie',
07:17:49 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:17:49 INFO - 'USERDOMAIN': 'T-W732-IX-046',
07:17:49 INFO - 'USERNAME': 'cltbld',
07:17:49 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
07:17:49 INFO - 'WINDIR': 'C:\\windows',
07:17:49 INFO - 'WINDOWS_TRACING_FLAGS': '3',
07:17:49 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
07:17:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
07:17:50 INFO - Ignoring indexes: https://pypi.python.org/simple/
07:17:50 INFO - Downloading/unpacking mozsystemmonitor==0.3
07:17:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
07:17:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
07:17:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz
07:17:51 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor
07:17:51 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:17:51 INFO - Installing collected packages: mozsystemmonitor
07:17:51 INFO - Running setup.py install for mozsystemmonitor
07:17:51 INFO - Successfully installed mozsystemmonitor
07:17:51 INFO - Cleaning up...
07:17:51 INFO - Return code: 0
07:17:51 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv
07:17:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
07:17:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
07:17:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
07:17:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
07:17:51 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 0x017C2640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01869E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0176B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01883E90>, '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 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01939520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-046', 'USERDOMAIN': 'T-W732-IX-046', '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-046', '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:17:51 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:17:51 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:17:51 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
07:17:51 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
07:17:51 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
07:17:51 INFO - 'COMPUTERNAME': 'T-W732-IX-046',
07:17:51 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
07:17:51 INFO - 'CYGWINBASE': 'C:\\cygwin',
07:17:51 INFO - 'DCLOCATION': 'SCL3',
07:17:51 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
07:17:51 INFO - 'FP_NO_HOST_CHECK': 'NO',
07:17:51 INFO - 'HOMEDRIVE': 'C:',
07:17:51 INFO - 'HOMEPATH': '\\Users\\cltbld',
07:17:51 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
07:17:51 INFO - 'KTS_VERSION': '1.19c',
07:17:51 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
07:17:51 INFO - 'LOGONSERVER': '\\\\T-W732-IX-046',
07:17:51 INFO - 'MONDIR': 'C:\\Monitor_config\\',
07:17:51 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
07:17:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
07:17:51 INFO - 'MOZ_NO_REMOTE': '1',
07:17:51 INFO - 'NO_EM_RESTART': '1',
07:17:51 INFO - 'NUMBER_OF_PROCESSORS': '8',
07:17:51 INFO - 'OS': 'Windows_NT',
07:17:51 INFO - 'OURDRIVE': 'C:',
07:17:51 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:17:51 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
07:17:51 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
07:17:51 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
07:17:51 INFO - 'PROCESSOR_LEVEL': '6',
07:17:51 INFO - 'PROCESSOR_REVISION': '1e05',
07:17:51 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
07:17:51 INFO - 'PROGRAMFILES': 'C:\\Program Files',
07:17:51 INFO - 'PROMPT': '$P$G',
07:17:51 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
07:17:51 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
07:17:51 INFO - 'PUBLIC': 'C:\\Users\\Public',
07:17:51 INFO - 'PWD': 'C:\\slave\\test',
07:17:51 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
07:17:51 INFO - 'SLAVEDIR': 'C:\\slave\\',
07:17:51 INFO - 'SYSTEMDRIVE': 'C:',
07:17:51 INFO - 'SYSTEMROOT': 'C:\\windows',
07:17:51 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:17:51 INFO - 'TEST1': 'testie',
07:17:51 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:17:51 INFO - 'USERDOMAIN': 'T-W732-IX-046',
07:17:51 INFO - 'USERNAME': 'cltbld',
07:17:51 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
07:17:51 INFO - 'WINDIR': 'C:\\windows',
07:17:51 INFO - 'WINDOWS_TRACING_FLAGS': '3',
07:17:51 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
07:17:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
07:17:51 INFO - Ignoring indexes: https://pypi.python.org/simple/
07:17:51 INFO - Downloading/unpacking jsonschema==2.5.1
07:17:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
07:17:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
07:17:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl
07:17:52 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1)
07:17:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
07:17:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
07:17:53 INFO - Downloading functools32-3.2.3-2.tar.gz
07:17:53 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32
07:17:53 INFO - warning: no files found matching '*.txt'
07:17:53 INFO - no previously-included directories found matching 'build'
07:17:53 INFO - no previously-included directories found matching 'dist'
07:17:53 INFO - no previously-included directories found matching '.git*'
07:17:53 INFO - Installing collected packages: jsonschema, functools32
07:17:53 INFO - Running setup.py install for functools32
07:17:53 INFO - warning: no files found matching '*.txt'
07:17:53 INFO - no previously-included directories found matching 'build'
07:17:53 INFO - no previously-included directories found matching 'dist'
07:17:53 INFO - no previously-included directories found matching '.git*'
07:17:53 INFO - Successfully installed jsonschema functools32
07:17:53 INFO - Cleaning up...
07:17:53 INFO - Return code: 0
07:17:53 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv
07:17:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
07:17:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
07:17:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
07:17:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
07:17:53 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x017C2640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01869E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0176B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01883E90>, '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 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01939520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-046', 'USERDOMAIN': 'T-W732-IX-046', '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-046', '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:17:53 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in C:\slave\test\build
07:17:53 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2
07:17:53 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
07:17:53 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
07:17:53 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
07:17:53 INFO - 'COMPUTERNAME': 'T-W732-IX-046',
07:17:53 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
07:17:53 INFO - 'CYGWINBASE': 'C:\\cygwin',
07:17:53 INFO - 'DCLOCATION': 'SCL3',
07:17:53 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
07:17:53 INFO - 'FP_NO_HOST_CHECK': 'NO',
07:17:53 INFO - 'HOMEDRIVE': 'C:',
07:17:53 INFO - 'HOMEPATH': '\\Users\\cltbld',
07:17:53 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
07:17:53 INFO - 'KTS_VERSION': '1.19c',
07:17:53 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
07:17:53 INFO - 'LOGONSERVER': '\\\\T-W732-IX-046',
07:17:53 INFO - 'MONDIR': 'C:\\Monitor_config\\',
07:17:53 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
07:17:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
07:17:53 INFO - 'MOZ_NO_REMOTE': '1',
07:17:53 INFO - 'NO_EM_RESTART': '1',
07:17:53 INFO - 'NUMBER_OF_PROCESSORS': '8',
07:17:53 INFO - 'OS': 'Windows_NT',
07:17:53 INFO - 'OURDRIVE': 'C:',
07:17:53 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
07:17:53 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
07:17:53 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
07:17:53 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
07:17:53 INFO - 'PROCESSOR_LEVEL': '6',
07:17:53 INFO - 'PROCESSOR_REVISION': '1e05',
07:17:53 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
07:17:53 INFO - 'PROGRAMFILES': 'C:\\Program Files',
07:17:53 INFO - 'PROMPT': '$P$G',
07:17:53 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
07:17:53 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
07:17:53 INFO - 'PUBLIC': 'C:\\Users\\Public',
07:17:53 INFO - 'PWD': 'C:\\slave\\test',
07:17:53 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
07:17:53 INFO - 'SLAVEDIR': 'C:\\slave\\',
07:17:53 INFO - 'SYSTEMDRIVE': 'C:',
07:17:53 INFO - 'SYSTEMROOT': 'C:\\windows',
07:17:53 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:17:53 INFO - 'TEST1': 'testie',
07:17:53 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:17:53 INFO - 'USERDOMAIN': 'T-W732-IX-046',
07:17:53 INFO - 'USERNAME': 'cltbld',
07:17:53 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
07:17:53 INFO - 'WINDIR': 'C:\\windows',
07:17:53 INFO - 'WINDOWS_TRACING_FLAGS': '3',
07:17:53 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
07:17:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
07:17:54 INFO - Ignoring indexes: https://pypi.python.org/simple/
07:17:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages
07:17:54 INFO - Cleaning up...
07:17:54 INFO - Return code: 0
07:17:54 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv
07:17: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:17:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
07:17: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:17:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
07:17: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', '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 0x017C2640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01869E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0176B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01883E90>, '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 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01939520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-046', 'USERDOMAIN': 'T-W732-IX-046', '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-046', '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:17:54 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:17:54 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:17:54 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
07:17:54 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
07:17:54 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
07:17:54 INFO - 'COMPUTERNAME': 'T-W732-IX-046',
07:17:54 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
07:17:54 INFO - 'CYGWINBASE': 'C:\\cygwin',
07:17:54 INFO - 'DCLOCATION': 'SCL3',
07:17:54 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
07:17:54 INFO - 'FP_NO_HOST_CHECK': 'NO',
07:17:54 INFO - 'HOMEDRIVE': 'C:',
07:17:54 INFO - 'HOMEPATH': '\\Users\\cltbld',
07:17:54 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
07:17:54 INFO - 'KTS_VERSION': '1.19c',
07:17:54 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
07:17:54 INFO - 'LOGONSERVER': '\\\\T-W732-IX-046',
07:17:54 INFO - 'MONDIR': 'C:\\Monitor_config\\',
07:17:54 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
07:17:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
07:17:54 INFO - 'MOZ_NO_REMOTE': '1',
07:17:54 INFO - 'NO_EM_RESTART': '1',
07:17:54 INFO - 'NUMBER_OF_PROCESSORS': '8',
07:17:54 INFO - 'OS': 'Windows_NT',
07:17:54 INFO - 'OURDRIVE': 'C:',
07:17:54 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:17:54 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
07:17:54 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
07:17:54 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
07:17:54 INFO - 'PROCESSOR_LEVEL': '6',
07:17:54 INFO - 'PROCESSOR_REVISION': '1e05',
07:17:54 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
07:17:54 INFO - 'PROGRAMFILES': 'C:\\Program Files',
07:17:54 INFO - 'PROMPT': '$P$G',
07:17:54 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
07:17:54 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
07:17:54 INFO - 'PUBLIC': 'C:\\Users\\Public',
07:17:54 INFO - 'PWD': 'C:\\slave\\test',
07:17:54 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
07:17:54 INFO - 'SLAVEDIR': 'C:\\slave\\',
07:17:54 INFO - 'SYSTEMDRIVE': 'C:',
07:17:54 INFO - 'SYSTEMROOT': 'C:\\windows',
07:17:54 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:17:54 INFO - 'TEST1': 'testie',
07:17:54 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:17:54 INFO - 'USERDOMAIN': 'T-W732-IX-046',
07:17:54 INFO - 'USERNAME': 'cltbld',
07:17:54 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
07:17:54 INFO - 'WINDIR': 'C:\\windows',
07:17:54 INFO - 'WINDOWS_TRACING_FLAGS': '3',
07:17:54 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
07:17:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
07:17:54 INFO - Ignoring indexes: https://pypi.python.org/simple/
07:17:54 INFO - Downloading/unpacking blobuploader==1.2.4
07:17:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
07:17:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
07:17:55 INFO - Downloading blobuploader-1.2.4.tar.gz
07:17:55 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader
07:17:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4)
07:17:55 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:17:55 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:17:55 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests
07:17:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4)
07:17:56 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:17:56 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:17:56 INFO - Downloading docopt-0.6.1.tar.gz
07:17:56 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt
07:17:56 INFO - Installing collected packages: blobuploader, requests, docopt
07:17:56 INFO - Running setup.py install for blobuploader
07:17:57 INFO - Running setup.py install for requests
07:17:58 INFO - Running setup.py install for docopt
07:17:58 INFO - Successfully installed blobuploader requests docopt
07:17:58 INFO - Cleaning up...
07:17:59 INFO - Return code: 0
07:17:59 INFO - Installing None into virtualenv C:\slave\test\build/venv
07:17:59 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:17:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
07:17:59 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:17:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
07:17:59 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 0x017C2640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01869E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0176B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01883E90>, '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 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01939520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-046', 'USERDOMAIN': 'T-W732-IX-046', '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-046', '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:17:59 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:17:59 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:17:59 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
07:17:59 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
07:17:59 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
07:17:59 INFO - 'COMPUTERNAME': 'T-W732-IX-046',
07:17:59 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
07:17:59 INFO - 'CYGWINBASE': 'C:\\cygwin',
07:17:59 INFO - 'DCLOCATION': 'SCL3',
07:17:59 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
07:17:59 INFO - 'FP_NO_HOST_CHECK': 'NO',
07:17:59 INFO - 'HOMEDRIVE': 'C:',
07:17:59 INFO - 'HOMEPATH': '\\Users\\cltbld',
07:17:59 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
07:17:59 INFO - 'KTS_VERSION': '1.19c',
07:17:59 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
07:17:59 INFO - 'LOGONSERVER': '\\\\T-W732-IX-046',
07:17:59 INFO - 'MONDIR': 'C:\\Monitor_config\\',
07:17:59 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
07:17:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
07:17:59 INFO - 'MOZ_NO_REMOTE': '1',
07:17:59 INFO - 'NO_EM_RESTART': '1',
07:17:59 INFO - 'NUMBER_OF_PROCESSORS': '8',
07:17:59 INFO - 'OS': 'Windows_NT',
07:17:59 INFO - 'OURDRIVE': 'C:',
07:17:59 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:17:59 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
07:17:59 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
07:17:59 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
07:17:59 INFO - 'PROCESSOR_LEVEL': '6',
07:17:59 INFO - 'PROCESSOR_REVISION': '1e05',
07:17:59 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
07:17:59 INFO - 'PROGRAMFILES': 'C:\\Program Files',
07:17:59 INFO - 'PROMPT': '$P$G',
07:17:59 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
07:17:59 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
07:17:59 INFO - 'PUBLIC': 'C:\\Users\\Public',
07:17:59 INFO - 'PWD': 'C:\\slave\\test',
07:17:59 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
07:17:59 INFO - 'SLAVEDIR': 'C:\\slave\\',
07:17:59 INFO - 'SYSTEMDRIVE': 'C:',
07:17:59 INFO - 'SYSTEMROOT': 'C:\\windows',
07:17:59 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:17:59 INFO - 'TEST1': 'testie',
07:17:59 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:17:59 INFO - 'USERDOMAIN': 'T-W732-IX-046',
07:17:59 INFO - 'USERNAME': 'cltbld',
07:17:59 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
07:17:59 INFO - 'WINDIR': 'C:\\windows',
07:17:59 INFO - 'WINDOWS_TRACING_FLAGS': '3',
07:17:59 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
07:17:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
07:17:59 INFO - Ignoring indexes: https://pypi.python.org/simple/
07:17:59 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
07:17:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-eikzzz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
07:18:00 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
07:18:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-oedugs-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
07:18:00 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
07:18:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qc6ctv-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
07:18:00 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
07:18:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qxyp9z-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
07:18:00 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
07:18:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-8wjlbh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
07:18:00 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
07:18:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xnts1_-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
07:18:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
07:18:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ci5su9-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
07:18:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
07:18:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-l8cqvm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
07:18:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
07:18:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ktkd0e-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
07:18:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
07:18:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hbsrd9-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
07:18:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
07:18:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cczst5-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
07:18:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
07:18:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ccsmrw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
07:18:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
07:18:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-w6yuet-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
07:18:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
07:18:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mk3pby-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
07:18:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
07:18:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pbcsgv-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
07:18:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
07:18:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-3ck0bd-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
07:18:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
07:18:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hy_rva-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
07:18:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
07:18:03 INFO - Running setup.py install for manifestparser
07:18:03 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts
07:18:03 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts
07:18:03 INFO - Running setup.py install for mozcrash
07:18:03 INFO - Running setup.py install for mozdebug
07:18:04 INFO - Running setup.py install for mozdevice
07:18:04 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts
07:18:04 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts
07:18:04 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts
07:18:04 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts
07:18:04 INFO - Running setup.py install for mozfile
07:18:05 INFO - Running setup.py install for mozhttpd
07:18:05 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts
07:18:05 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts
07:18:05 INFO - Running setup.py install for mozinfo
07:18:05 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts
07:18:05 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts
07:18:06 INFO - Running setup.py install for mozInstall
07:18:06 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts
07:18:06 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts
07:18:06 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts
07:18:06 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts
07:18:06 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts
07:18:06 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts
07:18:06 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts
07:18:06 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts
07:18:06 INFO - Running setup.py install for mozleak
07:18:06 INFO - Running setup.py install for mozlog
07:18:06 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts
07:18:06 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts
07:18:07 INFO - Running setup.py install for moznetwork
07:18:07 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts
07:18:07 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts
07:18:07 INFO - Running setup.py install for mozprocess
07:18:08 INFO - Running setup.py install for mozprofile
07:18:08 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts
07:18:08 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts
07:18:08 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts
07:18:08 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts
07:18:08 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts
07:18:08 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts
07:18:09 INFO - Running setup.py install for mozrunner
07:18:09 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts
07:18:09 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts
07:18:09 INFO - Running setup.py install for mozscreenshot
07:18:10 INFO - Running setup.py install for moztest
07:18:10 INFO - Running setup.py install for mozversion
07:18:10 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts
07:18:10 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts
07:18:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion
07:18:10 INFO - Cleaning up...
07:18:10 INFO - Return code: 0
07:18:10 INFO - Installing None into virtualenv C:\slave\test\build/venv
07:18:10 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:18:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
07:18:10 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:18:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
07:18:10 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 0x017C2640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01869E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0176B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01883E90>, '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 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01939520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-046', 'USERDOMAIN': 'T-W732-IX-046', '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-046', '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:18:10 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:18:10 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:18:10 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
07:18:10 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
07:18:10 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
07:18:10 INFO - 'COMPUTERNAME': 'T-W732-IX-046',
07:18:10 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
07:18:10 INFO - 'CYGWINBASE': 'C:\\cygwin',
07:18:10 INFO - 'DCLOCATION': 'SCL3',
07:18:10 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
07:18:10 INFO - 'FP_NO_HOST_CHECK': 'NO',
07:18:10 INFO - 'HOMEDRIVE': 'C:',
07:18:10 INFO - 'HOMEPATH': '\\Users\\cltbld',
07:18:10 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
07:18:10 INFO - 'KTS_VERSION': '1.19c',
07:18:10 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
07:18:10 INFO - 'LOGONSERVER': '\\\\T-W732-IX-046',
07:18:10 INFO - 'MONDIR': 'C:\\Monitor_config\\',
07:18:10 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
07:18:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
07:18:10 INFO - 'MOZ_NO_REMOTE': '1',
07:18:10 INFO - 'NO_EM_RESTART': '1',
07:18:10 INFO - 'NUMBER_OF_PROCESSORS': '8',
07:18:10 INFO - 'OS': 'Windows_NT',
07:18:10 INFO - 'OURDRIVE': 'C:',
07:18:10 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:18:10 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
07:18:10 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
07:18:10 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
07:18:10 INFO - 'PROCESSOR_LEVEL': '6',
07:18:10 INFO - 'PROCESSOR_REVISION': '1e05',
07:18:10 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
07:18:10 INFO - 'PROGRAMFILES': 'C:\\Program Files',
07:18:10 INFO - 'PROMPT': '$P$G',
07:18:10 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
07:18:10 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
07:18:10 INFO - 'PUBLIC': 'C:\\Users\\Public',
07:18:10 INFO - 'PWD': 'C:\\slave\\test',
07:18:10 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
07:18:10 INFO - 'SLAVEDIR': 'C:\\slave\\',
07:18:10 INFO - 'SYSTEMDRIVE': 'C:',
07:18:10 INFO - 'SYSTEMROOT': 'C:\\windows',
07:18:11 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:18:11 INFO - 'TEST1': 'testie',
07:18:11 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:18:11 INFO - 'USERDOMAIN': 'T-W732-IX-046',
07:18:11 INFO - 'USERNAME': 'cltbld',
07:18:11 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
07:18:11 INFO - 'WINDIR': 'C:\\windows',
07:18:11 INFO - 'WINDOWS_TRACING_FLAGS': '3',
07:18:11 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
07:18:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
07:18:11 INFO - Ignoring indexes: https://pypi.python.org/simple/
07:18:11 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
07:18:11 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ou9ckb-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
07:18:11 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:18:11 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
07:18:11 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-6rrenh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
07:18:11 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:18:11 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
07:18:11 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-avprbs-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
07:18:11 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:18:11 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
07:18:11 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-a6m4t9-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
07:18:11 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:18:11 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
07:18:11 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mi3qps-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
07:18:12 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:18:12 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
07:18:12 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ifewec-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
07:18:12 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:18:12 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
07:18:12 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-yzonyq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
07:18:12 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:18:12 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
07:18:12 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kgyvxs-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
07:18:12 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:18:12 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
07:18:12 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-6bkkko-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
07:18:12 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:18:12 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
07:18:12 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xbebps-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
07:18:12 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:18:12 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
07:18:12 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vlzddk-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
07:18:13 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:18:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
07:18:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-nsw71z-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
07:18:13 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:18:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
07:18:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-u4dtct-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
07:18:13 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:18:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
07:18:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-oab2fo-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
07:18:13 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:18:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
07:18:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vv9ncl-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
07:18:13 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:18:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
07:18:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zgekit-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
07:18:13 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:18:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
07:18:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-smbwd9-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
07:18:13 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:18:13 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:18:13 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:18:13 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:18:13 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:18:13 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:18:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10))
07:18:13 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:18:13 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:18:15 INFO - Downloading blessings-1.6.tar.gz
07:18:15 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings
07:18:15 INFO - Installing collected packages: blessings
07:18:15 INFO - Running setup.py install for blessings
07:18:15 INFO - Successfully installed blessings
07:18:15 INFO - Cleaning up...
07:18:18 INFO - Return code: 0
07:18:18 INFO - Done creating virtualenv C:\slave\test\build/venv.
07:18:18 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
07:18:18 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
07:18:18 INFO - Reading from file tmpfile_stdout
07:18:18 INFO - Using _rmtree_windows ...
07:18:18 INFO - Using _rmtree_windows ...
07:18:18 INFO - Current package versions:
07:18:18 INFO - blessings == 1.6
07:18:18 INFO - blobuploader == 1.2.4
07:18:18 INFO - docopt == 0.6.1
07:18:18 INFO - functools32 == 3.2.3-2
07:18:18 INFO - jsonschema == 2.5.1
07:18:18 INFO - manifestparser == 1.1
07:18:18 INFO - mozInstall == 1.12
07:18:18 INFO - mozcrash == 0.17
07:18:18 INFO - mozdebug == 0.1
07:18:18 INFO - mozdevice == 0.48
07:18:18 INFO - mozfile == 1.2
07:18:18 INFO - mozhttpd == 0.7
07:18:18 INFO - mozinfo == 0.9
07:18:18 INFO - mozleak == 0.1
07:18:18 INFO - mozlog == 3.4
07:18:18 INFO - moznetwork == 0.27
07:18:18 INFO - mozprocess == 0.23
07:18:18 INFO - mozprofile == 0.28
07:18:18 INFO - mozrunner == 6.12
07:18:18 INFO - mozscreenshot == 0.1
07:18:18 INFO - mozsystemmonitor == 0.3
07:18:18 INFO - moztest == 0.7
07:18:18 INFO - mozversion == 1.4
07:18:18 INFO - psutil == 3.1.1
07:18:18 INFO - requests == 1.2.3
07:18:18 INFO - Installing None into virtualenv C:\slave\test\build/venv
07:18:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
07:18:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
07:18:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
07:18:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
07:18:18 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 0x017C2640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01869E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0176B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01883E90>, '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 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01939520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-046', 'USERDOMAIN': 'T-W732-IX-046', '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-046', '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:18:18 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:18:18 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:18:18 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
07:18:18 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
07:18:18 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
07:18:18 INFO - 'COMPUTERNAME': 'T-W732-IX-046',
07:18:18 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
07:18:18 INFO - 'CYGWINBASE': 'C:\\cygwin',
07:18:18 INFO - 'DCLOCATION': 'SCL3',
07:18:18 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
07:18:18 INFO - 'FP_NO_HOST_CHECK': 'NO',
07:18:18 INFO - 'HOMEDRIVE': 'C:',
07:18:18 INFO - 'HOMEPATH': '\\Users\\cltbld',
07:18:18 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
07:18:18 INFO - 'KTS_VERSION': '1.19c',
07:18:18 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
07:18:18 INFO - 'LOGONSERVER': '\\\\T-W732-IX-046',
07:18:18 INFO - 'MONDIR': 'C:\\Monitor_config\\',
07:18:18 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
07:18:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
07:18:18 INFO - 'MOZ_NO_REMOTE': '1',
07:18:18 INFO - 'NO_EM_RESTART': '1',
07:18:18 INFO - 'NUMBER_OF_PROCESSORS': '8',
07:18:18 INFO - 'OS': 'Windows_NT',
07:18:18 INFO - 'OURDRIVE': 'C:',
07:18:18 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
07:18:18 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
07:18:18 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
07:18:18 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
07:18:18 INFO - 'PROCESSOR_LEVEL': '6',
07:18:18 INFO - 'PROCESSOR_REVISION': '1e05',
07:18:18 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
07:18:18 INFO - 'PROGRAMFILES': 'C:\\Program Files',
07:18:18 INFO - 'PROMPT': '$P$G',
07:18:18 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
07:18:18 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
07:18:18 INFO - 'PUBLIC': 'C:\\Users\\Public',
07:18:18 INFO - 'PWD': 'C:\\slave\\test',
07:18:18 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
07:18:18 INFO - 'SLAVEDIR': 'C:\\slave\\',
07:18:18 INFO - 'SYSTEMDRIVE': 'C:',
07:18:18 INFO - 'SYSTEMROOT': 'C:\\windows',
07:18:18 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:18:18 INFO - 'TEST1': 'testie',
07:18:18 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:18:18 INFO - 'USERDOMAIN': 'T-W732-IX-046',
07:18:18 INFO - 'USERNAME': 'cltbld',
07:18:18 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
07:18:18 INFO - 'WINDIR': 'C:\\windows',
07:18:18 INFO - 'WINDOWS_TRACING_FLAGS': '3',
07:18:18 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
07:18:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
07:18:19 INFO - Ignoring indexes: https://pypi.python.org/simple/
07:18:19 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:18:19 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:18:19 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:18:19 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:18:19 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:18:19 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:18:19 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:18:19 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:18:19 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:18:19 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:18:19 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:18:19 INFO - Cleaning up...
07:18:19 INFO - Return code: 0
07:18:19 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv
07:18:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
07:18:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
07:18:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
07:18:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
07:18:19 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017C2640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01869E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0176B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01883E90>, '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 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01939520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-046', 'USERDOMAIN': 'T-W732-IX-046', '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-046', '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:18:19 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in C:\slave\test\build
07:18:19 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema
07:18:19 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
07:18:19 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
07:18:19 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
07:18:19 INFO - 'COMPUTERNAME': 'T-W732-IX-046',
07:18:19 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
07:18:19 INFO - 'CYGWINBASE': 'C:\\cygwin',
07:18:19 INFO - 'DCLOCATION': 'SCL3',
07:18:19 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
07:18:19 INFO - 'FP_NO_HOST_CHECK': 'NO',
07:18:19 INFO - 'HOMEDRIVE': 'C:',
07:18:19 INFO - 'HOMEPATH': '\\Users\\cltbld',
07:18:19 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
07:18:19 INFO - 'KTS_VERSION': '1.19c',
07:18:19 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
07:18:19 INFO - 'LOGONSERVER': '\\\\T-W732-IX-046',
07:18:19 INFO - 'MONDIR': 'C:\\Monitor_config\\',
07:18:19 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
07:18:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
07:18:19 INFO - 'MOZ_NO_REMOTE': '1',
07:18:19 INFO - 'NO_EM_RESTART': '1',
07:18:19 INFO - 'NUMBER_OF_PROCESSORS': '8',
07:18:19 INFO - 'OS': 'Windows_NT',
07:18:19 INFO - 'OURDRIVE': 'C:',
07:18:19 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
07:18:19 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
07:18:19 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
07:18:19 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
07:18:19 INFO - 'PROCESSOR_LEVEL': '6',
07:18:19 INFO - 'PROCESSOR_REVISION': '1e05',
07:18:19 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
07:18:19 INFO - 'PROGRAMFILES': 'C:\\Program Files',
07:18:19 INFO - 'PROMPT': '$P$G',
07:18:19 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
07:18:19 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
07:18:19 INFO - 'PUBLIC': 'C:\\Users\\Public',
07:18:19 INFO - 'PWD': 'C:\\slave\\test',
07:18:19 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
07:18:19 INFO - 'SLAVEDIR': 'C:\\slave\\',
07:18:19 INFO - 'SYSTEMDRIVE': 'C:',
07:18:19 INFO - 'SYSTEMROOT': 'C:\\windows',
07:18:19 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:18:19 INFO - 'TEST1': 'testie',
07:18:19 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:18:19 INFO - 'USERDOMAIN': 'T-W732-IX-046',
07:18:19 INFO - 'USERNAME': 'cltbld',
07:18:19 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
07:18:19 INFO - 'WINDIR': 'C:\\windows',
07:18:19 INFO - 'WINDOWS_TRACING_FLAGS': '3',
07:18:19 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
07:18:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
07:18:19 INFO - Ignoring indexes: https://pypi.python.org/simple/
07:18:19 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages
07:18:19 INFO - Cleaning up...
07:18:19 INFO - Return code: 0
07:18:19 INFO - Running post-action listener: _resource_record_post_action
07:18:19 INFO - Running post-action listener: _start_resource_monitoring
07:18:19 INFO - Starting resource monitoring.
07:18:19 INFO - [mozharness: 2016-10-20 14:18:19.571000Z] Finished create-virtualenv step (success)
07:18:19 INFO - [mozharness: 2016-10-20 14:18:19.571000Z] Running install step.
07:18:19 INFO - Running pre-action listener: _resource_record_pre_action
07:18:19 INFO - Running main action method: install
07:18:19 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
07:18:19 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
07:18:19 INFO - Reading from file tmpfile_stdout
07:18:19 INFO - Using _rmtree_windows ...
07:18:19 INFO - Using _rmtree_windows ...
07:18:19 INFO - Detecting whether we're running mozinstall >=1.0...
07:18:19 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h']
07:18:19 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h
07:18:20 INFO - Reading from file tmpfile_stdout
07:18:20 INFO - Output received:
07:18:20 INFO - Usage: mozinstall-script.py [options] installer
07:18:20 INFO - Options:
07:18:20 INFO - -h, --help show this help message and exit
07:18:20 INFO - -d DEST, --destination=DEST
07:18:20 INFO - Directory to install application into. [default:
07:18:20 INFO - "C:\slave\test"]
07:18:20 INFO - --app=APP Application being installed. [default: firefox]
07:18:20 INFO - Using _rmtree_windows ...
07:18:20 INFO - Using _rmtree_windows ...
07:18:20 INFO - mkdir: C:\slave\test\build\application
07:18:20 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:18:20 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:18:22 INFO - Reading from file tmpfile_stdout
07:18:22 INFO - Output received:
07:18:22 INFO - C:\slave\test\build\application\firefox\firefox.exe
07:18:22 INFO - Using _rmtree_windows ...
07:18:22 INFO - Using _rmtree_windows ...
07:18:22 INFO - Running post-action listener: _resource_record_post_action
07:18:22 INFO - [mozharness: 2016-10-20 14:18:22.081000Z] Finished install step (success)
07:18:22 INFO - [mozharness: 2016-10-20 14:18:22.081000Z] Running run-tests step.
07:18:22 INFO - Running pre-action listener: _resource_record_pre_action
07:18:22 INFO - Running main action method: run_tests
07:18:22 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version']
07:18:22 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version
07:18:22 INFO - Python 2.7.3
07:18:22 INFO - Return code: 0
07:18:22 INFO - grabbing minidump binary from tooltool
07:18:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
07:18:22 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 0x01883E90>, '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 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01939520>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1
07:18:22 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:18:22 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:18:22 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:18:22 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'...
07:18:23 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmppxbpg0
07:18:23 INFO - INFO - File integrity verified, renaming tmppxbpg0 to win32-minidump_stackwalk.exe
07:18:23 INFO - Return code: 0
07:18:23 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755
07:18:23 INFO - mkdir: C:\slave\test\build\blobber_upload_dir
07:18:23 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir
07:18:23 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe
07:18:23 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir
07:18:23 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://queue.taskcluster.net/v1/task/WAAtoKMpTeeonEirK7BiOQ/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-046', '--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:18:23 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://queue.taskcluster.net/v1/task/WAAtoKMpTeeonEirK7BiOQ/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-046 --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:18:23 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
07:18:23 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
07:18:23 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
07:18:23 INFO - 'COMPUTERNAME': 'T-W732-IX-046',
07:18:23 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
07:18:23 INFO - 'CYGWINBASE': 'C:\\cygwin',
07:18:23 INFO - 'DCLOCATION': 'SCL3',
07:18:23 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
07:18:23 INFO - 'FP_NO_HOST_CHECK': 'NO',
07:18:23 INFO - 'HOMEDRIVE': 'C:',
07:18:23 INFO - 'HOMEPATH': '\\Users\\cltbld',
07:18:23 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
07:18:23 INFO - 'KTS_VERSION': '1.19c',
07:18:23 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
07:18:23 INFO - 'LOGONSERVER': '\\\\T-W732-IX-046',
07:18:23 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir',
07:18:23 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe',
07:18:23 INFO - 'MONDIR': 'C:\\Monitor_config\\',
07:18:23 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
07:18:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
07:18:23 INFO - 'MOZ_NO_REMOTE': '1',
07:18:23 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir',
07:18:23 INFO - 'NO_EM_RESTART': '1',
07:18:23 INFO - 'NUMBER_OF_PROCESSORS': '8',
07:18:23 INFO - 'OS': 'Windows_NT',
07:18:23 INFO - 'OURDRIVE': 'C:',
07:18:23 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
07:18:23 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
07:18:23 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
07:18:23 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
07:18:23 INFO - 'PROCESSOR_LEVEL': '6',
07:18:23 INFO - 'PROCESSOR_REVISION': '1e05',
07:18:23 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
07:18:23 INFO - 'PROGRAMFILES': 'C:\\Program Files',
07:18:23 INFO - 'PROMPT': '$P$G',
07:18:23 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
07:18:23 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
07:18:23 INFO - 'PUBLIC': 'C:\\Users\\Public',
07:18:23 INFO - 'PWD': 'C:\\slave\\test',
07:18:23 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos',
07:18:23 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
07:18:23 INFO - 'SLAVEDIR': 'C:\\slave\\',
07:18:23 INFO - 'SYSTEMDRIVE': 'C:',
07:18:23 INFO - 'SYSTEMROOT': 'C:\\windows',
07:18:23 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:18:23 INFO - 'TEST1': 'testie',
07:18:23 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
07:18:23 INFO - 'USERDOMAIN': 'T-W732-IX-046',
07:18:23 INFO - 'USERNAME': 'cltbld',
07:18:23 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
07:18:23 INFO - 'WINDIR': 'C:\\windows',
07:18:23 INFO - 'WINDOWS_TRACING_FLAGS': '3',
07:18:23 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
07:18:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
07:18:23 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://queue.taskcluster.net/v1/task/WAAtoKMpTeeonEirK7BiOQ/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-046', '--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:18:24 INFO - mozversion application_buildid: 20161020033007
07:18:24 INFO - mozversion application_changeset: 99a239e1866a57f987b08dad796528e4ea30e622
07:18:24 INFO - mozversion application_display_name: Nightly
07:18:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
07:18:24 INFO - mozversion application_name: Firefox
07:18:24 INFO - mozversion application_remotingname: firefox
07:18:24 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team
07:18:24 INFO - mozversion application_vendor: Mozilla
07:18:24 INFO - mozversion application_version: 52.0a1
07:18:24 INFO - mozversion platform_buildid: 20161020033007
07:18:24 INFO - mozversion platform_changeset: 99a239e1866a57f987b08dad796528e4ea30e622
07:18:24 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team
07:18:24 INFO - mozversion platform_version: 52.0a1
07:18:24 INFO - using testdate: 1476973104
07:18:24 INFO - actual date: 1476973104
07:18:24 INFO - starting webserver on 'localhost:49567'
07:18:24 INFO - SUITE-START | Running 2 tests
07:18:24 INFO - TEST-START | dromaeo_css
07:18:24 INFO - operating with platform_type : w7_
07:18:24 INFO - Initialising browser for dromaeo_css test...
07:18:24 INFO - TEST-INFO | started process 3848 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmptcostb\profile http://localhost:49567/getInfo.html)
07:18:32 INFO - PROCESS | 3848 | __metrics Screen width/height:1600/1200
07:18:32 INFO - PROCESS | 3848 | colorDepth:24
07:18:32 INFO - PROCESS | 3848 | Browser inner width/height: 1010/674
07:18:32 INFO - PROCESS | 3848 | __metrics
07:18:33 INFO - PROCESS | 3848 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows"
07:18:33 INFO - PROCESS | 3848 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
07:18:34 INFO - TEST-INFO | 3848: exit 0
07:18:34 INFO - Browser initialized.
07:18:34 INFO - Running cycle 1/1 for dromaeo_css test...
07:18:34 INFO - TEST-INFO | started process 3296 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmptcostb\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1)
07:18:35 INFO - PROCESS | 3296 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0
07:18:45 INFO - PROCESS | 3296 | RSS: Main: 122863616
07:18:45 INFO - PROCESS | 3296 |
07:19:37 INFO - PROCESS | 3296 | 1476973177356 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:21:48 INFO - PROCESS | 3296 | Cycle 1(1): loaded http://localhost:49567/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49567/tests/dromaeo/cssquery-ext.html)
07:21:49 INFO - PROCESS | 3296 | RSS: Main: 190058496
07:21:49 INFO - PROCESS | 3296 |
07:24:51 INFO - PROCESS | 3296 | Cycle 1(1): loaded http://localhost:49567/tests/dromaeo/cssquery-ext.html (next: http://localhost:49567/tests/dromaeo/cssquery-jquery.html)
07:24:52 INFO - PROCESS | 3296 | RSS: Main: 198774784
07:24:52 INFO - PROCESS | 3296 |
07:27:54 INFO - PROCESS | 3296 | Cycle 1(1): loaded http://localhost:49567/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49567/tests/dromaeo/cssquery-mootools.html)
07:27:54 INFO - PROCESS | 3296 | RSS: Main: 157704192
07:27:54 INFO - PROCESS | 3296 |
07:30:56 INFO - PROCESS | 3296 | Cycle 1(1): loaded http://localhost:49567/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49567/tests/dromaeo/cssquery-prototype.html)
07:30:57 INFO - PROCESS | 3296 | RSS: Main: 142839808
07:30:57 INFO - PROCESS | 3296 |
07:34:00 INFO - PROCESS | 3296 | Cycle 1(1): loaded http://localhost:49567/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49567/tests/dromaeo/cssquery-yui.html)
07:34:00 INFO - PROCESS | 3296 | RSS: Main: 133144576
07:34:00 INFO - PROCESS | 3296 |
07:37:03 INFO - PROCESS | 3296 | Cycle 1(1): loaded http://localhost:49567/tests/dromaeo/cssquery-yui.html (next: http://localhost:49567/tests/dromaeo/cssquery-dojo.html)
07:37:03 INFO - PROCESS | 3296 | RSS: Main: 185761792
07:37:03 INFO - PROCESS | 3296 |
07:37:03 INFO - PROCESS | 3296 | __start_tp_report
07:37:03 INFO - PROCESS | 3296 | _x_x_mozilla_page_load
07:37:03 INFO - PROCESS | 3296 | _x_x_mozilla_page_load_details
07:37:03 INFO - PROCESS | 3296 | |i|pagename|runs|
07:37:03 INFO - PROCESS | 3296 | |0;dojo.html;2676.3844315807364;2743.7530622244;2749.2302155396496;2755.297399163213;2760.9861950690256;4412.867613971582;4630.8842278943;4631.073785242948;4756.048790241948;4791.377120974277;4616.584507394334;4653.418322709661;4669.999999999992;4816.4461086974925;4829.396325459317;4066.1461093170396;4151.813168407414;4165.875023749292;4172.687048471362;4186.099988502471;4046.291898917678;4103.774292413916;4131.607497287753;4149.045719484505;4155.355919832416;4122.711410201287;4185.4977402711565;4199.811008504609;4205.7897105144775;4212.852550160745;4021.77880216588;4052.756834589934;4064.1262128642484;4076.8369265229353;4123.381492776108;4006.09862780872;4071;4163.375493675942;4163.75017498951;4168.854090106862;4609.976950115228;4610.2623580227;4704.411948506437;4719.197736384823;4722.150012997659;4721.244600863845;4745.572898439123;4869.586085182764;4872.9756351218375;4994.650374473788;4873.561379475864;4881.1213981483315;4881.487443818383;4933.605311575066;4970.776315065831;4448.377227188196;4587.082583483283;4610.56199661035;4615.953840461586;4631;5022.8196373852115;5060.544550990428;5088.821891233789;5088.898222035538;5097.592192624581;4829.27560865873;4859.489753575895;4885.487023862514;4887.73117478535;4890.755462226874;4894.926576101361;4917.459079501252;4934.210526315772;4950.579200767975;4954.702717836942;4915.410150781929;4926.285688575208;4928;4929.630277729185;4964.503549645006;4911.533404326583;4918.360613120271;5027.170516864677;5034.446210916796;5042.016806722654;3270.9345813083605;3312.7184189343693;3347.6317605063423;3347.9497807532907;3350.463925771865;3030.848457577112;3047.040182342555;3055.4805683033937;3056.969430305713;3074.13924101252;4603.332516785047;4634.490206077328;4639.048994956026;4649.883752906137;4651.5813576778255;4924.162892308244;4945.703257804544;4965.776540055706;4977.278294647256;4979.0788704089855;4537.6143027843;4640.95359046412;4643.048175124159;4645.488996210414;4646.79089440976;4153.397757325171;4155.335146376565;4156.06488540076;4168.458100446983;4168.7707176105;4933.654644174945;5025.195968645073;5074.797008119708;5080.288759573588;5097.643164978416;4822.204336284473;4930.4822993586595;5042.067217564762;5044.495550444926;5054.29239906406;4904.779284932115;4915.43472500667;4941.481144479778;5075.365579302587;5154.484551544815;4816.927746083882;4847.151748444078;4868.318435419115;4880.755962201804;4959.256111583291;4953.603711702983;5065.949340506548;5069.7211653357945;5073.34046573943;5078.619103567321;4788.057464577632;4809.2064809306075;4851.369321988119;4918.336024636628;4922.212446008764;4593.494715581351;4751.097291514601;4819.783109759999;4913.729744864067;4936.3829521309835;4433.490148632948;4664.7667616618355;4684.812607495798;4733.810647574059;4744.430668319824;4771.403574553181;4812.965212479334;4855.053264613508;4877.561019508261;4879.853604391874;4676.55572720591;4756.881077973078;4758.1435341638835;4843.903121937612;4851.684640498356
07:37:03 INFO - PROCESS | 3296 | |1;ext.html;538.4346436241946;542.4304480295689;545.9945400545995;568.8179782469606;575.6402248594628;28107.437851242987;28348.858255708743;28850;38689.61310386896;39374.606253937454;38750.41874371887;39678.404823927594;40341.39487907676;40404.79797601008;40478.797606011925;8003.119656837758;8074.636641351153;8133.918660813375;8137;8139.430239883211;6782.626955517445;6961;7939.325157361631;8019.1980801919635;9863.112319891208;9789.265805064613;9838.803223935516;10027.398356098622;10058.849117263248;10131.594736210543;9825.803483930282;10013.349132306377;10084.899151008469;10103.595856165744;10108.191344692406;6312.274088479838;6905.654717264117;9602.327837051409;9725.805483890283;10011.048950349705;35745.64254357449;37450.625493744985;37860;37862.24275514474;37893.81053094744;2836.8297902125937;3158.652548219694;3477.1480987158307;3503.544539209912;3558.6975107115927;29253.853730731422;29514.55728164079;29733.10800675983;30387.240318991982;30427.391452171054;3464.5842498900292;3483.4600636901323;3485.262716768769;3508.122969257686;3552.289542091592;250078;319921;325395;326943;327308;41953.580464194965;43458.7827060869;48786.268205976936;49218.26172607414;50371.74814125979;50581.44766970917;50656.24015639768;50920.490795092315;53350.46649533533;53797.1930421044;13593.184408935298;13653.590392288248;13790.379432925542;13806.654833629038;13809.723805524034;10480.790384192274;10505.31715438494;10532.789344213226;10552;10554;12990.480380784662;13359.064865459324;14999.475018374305;15243.771343429857;15488.5353439397;6270.561060725797;6310.936890631035;6319.652419116901;6328.8417789554705;6400.039994000937;710.2364957670421;732.0922056649822;774.5623722596828;790.9169537198626;868.1795703060674;25557.616635750477;26588.202353929413;26676.532050833266;26849.86575067112;27006.459870802475;803.3974519110667;826.2233500509502;860.8493513635088;873.3144481581928;985.4481490365418;13824.032317737661;14024.523975675529;14130.293485325692;14157.646058848406;14169.433222670978;446714;447035;447439;448197;449053;48075;48105.03789924182;48253.51746482561;48258.5174148254;48273.75863120662;920.7974245665943;978.1148061004802;1048.9580416783397;1091.1925175370168;1098.906592939609;27949.441011180068;28034;28545.714542855138;28599.14202573926;28658.426831462835;40529;41524;41540.376894346;42067.57932420637;42240;387752;388208;389044;389735;390391;22619.208327709108;23159.305220843402;23310.88344558266;23981.880090600134;24006.39984000414;41741;41971.790141049096;42326;43135.9216019602;43148.13703725971;34777.130571734895;35695.10762230861;35753.1061723459;36765;38360.80819595884;35369;35681.82159089188;35869.64130358663;36927.26145477022;37794.141908004305;34075.488867667504;34331.65668343285;34441;34674.133146671535;34698.826505867306;31629.20926976844;31747.841260794474;33044.339113218084;33868.83065584656;33968.830155849064;24683.876580616983;25256.873715631304;25632.8718356407;25678;25948.22155335343
07:37:03 INFO - PROCESS | 3296 | |2;jquery.html;271.1567026547436;284.77929604556465;295.89347834779477;301.3836703940442;306.0268346657629;12529.874701252984;16553.33786648534;16791.916040419797;17148;17713.64572708545;16563.668726625492;16833.41083062093;16941.91529042353;16990.204276669447;17190.57023574411;4957.702537847741;5064.594832413417;5235.11003129467;5237.554807841338;5238.842834714965;5090.974545127269;5183.688978661274;5189.792408303663;5189.896202075956;5207.036698210824;5092;5147.330846989905;5149.510796474329;5152.8711782205555;5167.509086636764;4994.87512812179;5122;5128.282040514331;5142.485751424828;5367.597430192751;5127.282180494733;5276.630635855492;5283.735813209324;5286.629935904488;5291.206319052135;20145.8992705037;21384.358469245948;21514.462138446506;21718.674219886558;21797.455063623376;1652.2812576529127;1656.2546853915785;1691.9492415227562;1724.8361405666442;1727.3349760342232;8636.740897773077;8895.688650897253;8896.37725359225;9066.41068330563;9158.725238242863;1641.9671606567922;1695.1439523040908;1700.1499250374814;1738.1830539646346;1740.3734655524001;82831.5858420704;84463;87319.56340218322;87879;88154;8922.73231803047;9001.144891235323;9118.179363857285;9250.537473126316;9260.722178334661;11044.392558409365;11591.304521728724;11619.593314233962;11622.825657615143;11624.53501859933;6692.665366731741;6891.483138764613;7027.8243043923285;7029.8242543935785;7144.106986626672;7197.604131772803;7210.242924492957;7426;7671.462997590115;7680.270374314431;7719.961400193076;7981;8016.118226995026;8121.918780812116;8144.429889907649;3284.786488878215;3295.31938711259;3301.9339613207603;3311.2549676322633;3384.390809654286;711.3135823929932;716.6631683108931;740.8036870229394;838.1450920061503;839.6431516605418;16916.91541542309;17523;17681.38115165963;17835.45742953741;17924.283028678707;770.8882212079217;773.5822655765936;798.2855344466669;836.782436566497;854.5043874552745;9578.473183974947;9718.271129665303;9726.465044422483;9756.756081098029;10048.59805607782;417926;429137;431194;440564.7971760185;440686;90412;93544.53227733818;93617;94265;94501;847.3390755210945;848.5333066813228;852.9104444033411;872.1191596487466;873.1879352202512;15094.54716358511;15450.30473628667;15458.14980176056;15876.444324448588;15925.203739813194;41376.1724765509;41568.792156039024;42031.7898410506;42058.57941420669;42305.365419519345;55621.72189139028;55852.16221756596;58626.70686646539;58764.70617647056;59445.10832337605;7024.086868707035;7116.217216106328;7128.144622645315;7268.781936541912;7440.144383395978;9786.510674466106;11855;11920.165588408729;12212.450439730412;12266.938665306616;9737.756556086155;9992.350497217658;10063.69808905734;10064.094231519199;10177.033181847713;9992.500374981368;10050.310977300625;10255;10456.32033917793;10563.841542377024;10374.948125259325;10461.843072353768;10604.257701960789;10621.734456638336;10641.308314959502;7219.133703955559;7269.963650181715;7370.852582948204;7385.9261407387385;7551.471397002157;9894.653687120915;9934.652287169914;10077.143442807443;10270.229732770222;10548.736281592715
07:37:03 INFO - PROCESS | 3296 | |3;mootools.html;1075.1936047964027;1164.7670465906817;1165.755191409804;1245.5142494427173;1476.527511196417;6116.44951954324;6730;6913.4123599494105;6955.478339124573;6984.546004509703;6969.825754356144;6972.302769723025;7013.368796808286;7014.333638304353;7015.123109611299;1870.3079860451653;1879.0510792049995;1879.4643526594855;1880.257298367151;1884.4346695991217;1825.0783354406005;1834.4955137337206;1856.9257229710795;1857.7306290587856;1880.0411789987136;1861.2741030997922;1866.1602278974449;1882.2282864025751;1882.28473180192;1887.1790771014585;1769.3984045424554;1800.486861244539;1808.3399559160944;1814.0929535232383;1814.1382843149531;1745.3193254630705;1745.987327350134;1749.9475015749547;1767.4432553745658;1771.929122835098;6666.400023997815;6691.765788197438;6719.764808231688;6760.087388202579;6781.559198652072;4062.1672557125717;4154.231467178582;4158.8336466541305;4159.08500129974;4159.355299928516;2700.972990270092;2756.986215068912;2794.9161525154072;2796.6224559684388;2798.1046065259125;844.9746507604782;863.1066845814552;878.8945326560854;880.3749337970049;880.9559522023873;8785.165409286108;8839.160279773476;8851.247643950272;8872;8903.688370907052;3414.4195486767308;3435.7766745161484;3485.721142308609;3508.9824550877083;3515.6660117288816;7072.469564782662;7124.43004559634;7150.606716630636;7155.427565794724;7168.569885806868;4004.1391100913443;4047.1298670785923;4061.1065565575527;4061.4517040199785;4065.573114822901;3369.2419205678525;3466.722662187019;3844.9807750961068;3846.1922996171115;3850.268448994682;2886.8845246189917;2947.9705202948126;2976.389840082778;2983.298924752681;2999.6400431948305;885.2342723544087;887.7958069644018;892.8392889279992;893;894.6421431427351;706.1455638677259;726.7456390263366;727.4435057181261;731.4055359316634;750.816050067726;4369.9126017479475;4403.075354175595;4418.690691651554;4473.396091527602;4546.318052292117;707.7664370757637;735.3565630073685;738.3244331436782;743.0711610486892;759.9354054905284;2997.7451916587333;3057;3058.9752432935215;3084.629844418639;3120.9063728088195;8097.39269554774;8213.630386632616;8254.092049874509;8359.373047021498;8360.916390836135;8205.507669539727;8301.377396695272;8302.211289927447;8308.792280193044;8318.251357377865;6516.218053833571;6545.21457425112;6560.934390656128;6584.703688334036;6608.537402381787;4301.892452688708;4378.518362980006;4405.735655860659;4453.465584129925;4458.933116003198;7630.694772209049;7822.256885595859;7843.19606490499;7859.607019649109;7873.488223265469;7456.2170972049325;7761;7965.522068675898;7972.720954766555;7981.72063977758;5685.886282274414;5973.402659733992;6042.45617894374;6139.2325959255095;6152.230971128609;6063.818085457443;6124.6325220486915;6473.838154045998;6656.434203092791;6740.191177058784;5782.219400381063;6064.3935606439;6188.44304012641;6259.280182778856;6294.181756371826;5449.427810080043;5895.4399332063385;5979.880402392015;6053.636781793107;6096.420840020191;5232.215167724872;5696.20253164549;5753.223314852608;5768.048272035235;5774.133879918046;3723.553173619183;3800.467934489229;3820.1595648957186;3969.92060158801;4001.679865610802;3841.865534706272;3884.514435695538;3972.7616343019513;3983.322835117979;4121.938170927378
07:37:03 INFO - PROCESS | 3296 | |4;prototype.html;239.1986844072359;242.42182394987952;248.34932476910498;249.34298124442094;249.79891187593992;5050.873728156794;5139.203423469359;5146.974265128674;5149.613778966574;5162.122439185343;4847.733374664392;4914.361133052707;4959.504049595048;4969.503049695038;4978.128827455199;3611.837467313964;3639.927201455976;3747.362948298796;3748.212875296191;3758.342290099222;3751.624837516254;3753.549574051118;3757.2861156380336;3758.2859256741267;3760.191558814854;3715.7398982071268;3722.162513434469;3725.3853114236254;3728.6830619397383;3731.9813400933094;3497.8425970831245;3593.1017245688577;3601.8559257629663;3682.4660424238336;3703.407454807245;3139.8744050237965;3218.4206842768294;3220.6618305077864;3221.4845624699938;3234.7897386669792;4880.853574392774;4901.534354236342;4925.926111108336;4928.162212423861;4936.456989731127;4203.180379825935;4354.9782251088545;4363.781810909441;4363.825446982117;4368.803403846835;5006.4743201964;5187.714675692835;5215.217717342391;5278.392984806719;5280.419153893069;3425.743069269815;3467.167879708877;3468.623992565101;3475.0964749165146;3475.721942244614;5514.641548299348;5523.7790488380415;5541.944580554224;5553.140132831595;5557.444255574451;3492.301539692072;3539.345221134047;3546.8226588670464;3549.432090865449;3552.3605750965075;3562.5012498250267;3563.429851223792;3594.3350480161253;3598.2443686825536;3598.7660802047785;4036.414719865662;4219.240536703423;4244.365919121161;4263.232618128766;4274.294741367639;5366.329208848894;5375.784968601212;5380.488853558905;5382.865428364244;5386.569074474111;1231.1935682128221;1240.7518496300595;1243.875612438749;1252.423885012886;1255.1653150654895;739.6930273936256;756.8448468064033;767.9001729775093;775.1318523253991;781.9257170568787;4902.656814022984;4969.875753106201;5076.263941728503;5082.33929589151;5094.872628184251;5494.120940649477;5512.751926163252;5534.335879694382;5535.58483113768;5540.722963851791;5083.449547887856;5083.542481176638;5084.46613105626;5089.312942752755;5105.617078719037;2782.8608569571848;2862.799604027698;2891.9277018074295;2909.461749576335;2938.397628486155;5644.689542075225;5656.519195868397;5661.518770904519;5664.688442135722;5671.858203544944;5517.310336207974;5524.088525393346;5584.553235741131;5593.692346920877;5604.943950560524;5460;5657.547396208293;5661.46216109469;5662.631928924607;5671.404502527175;5456.290682211287;5468.343798744176;5480.095784195563;5498.422665620213;5503;5325.201219816903;5394.811181608625;5404.32445944257;5411.2965314508865;5412.702301373305;5640.379558248507;5661.433856614306;5678.574106942078;5693.402192769865;5695.487406133468;3358.244395011103;3555.6444355564236;3557.982210088933;3563.7149028077174;3579.7673151245085;5402.405735369028;5410.891782164414;5434.592405569519;5468.972655136698;5483.479069488392;5317.229001794641;5318.680879147263;5327.147656374961;5328.520433161034;5338.4127745947135;5133.358330208724;5298.470152984825;5306.336707911511;5327.493888080626;5342.252084708221;5190.169572868323;5198.792048318176;5201.791928322825;5212.947870521398;5217.6086793489885;5135;5290.603204759582;5298.81454149103;5326.97336513315;5330;5144.819931302386;5187.58499320061;5264.6314757966575;5288.709120998382;5300.257963884982
07:37:03 INFO - PROCESS | 3296 | |5;yui.html;615.2586133708882;653.5947712418301;654.2541502686935;686.6841253023609;689.6207086102643;13874.861251387483;15114.546563603082;15174.544763657108;15188.392464301429;15200.163990980505;13551;13680;13834.861651383482;13870.37583308751;13874.861251387483;505.2522267044776;515.5334422347762;595.1667665268626;618.7370367593777;623.0778447897115;510.98467045988497;514.9691018538881;515.6699712184186;602.8975074237391;616.6669998200966;654.6693919570635;655.5050936542904;655.8917778566555;659.3735950846691;660.3825423229296;633.654658211276;639.8432384065934;648.2318452633597;652.5888690125191;655.6754406568755;384.04756204612437;388.78033910840566;459.2262038465197;460.67752573198896;476.6639519138999;13504.392302346418;13825.930870345685;13952.372143253477;14056.92971535146;14068.296585170701;12837;12839.486420543168;13010.804837927439;13149.86850131496;13401.46394144233;12802.48790048397;12883.420246088948;13172.53896113641;13289.53486627973;13302.268375239359;4068.5117785865887;4088.9591104088877;4220.113776107021;4254.170436764832;4258.190943720683;78063.21936780616;79753.60123199405;79964;82314.58842705809;82715.58642206811;10262.692119236435;10360.171186305077;10441.84337234942;10535.262531622713;10635.415052172204;19210.423687289403;19272.421827345202;19283.90358048201;19288.614227715367;19296;6895.34494223048;6990.405815505637;7080.468964827555;7105.502614817016;7157.105361829771;3611.566612006576;3699.8705045323286;3729.272791805626;3756.323861704919;3855.614438556122;2571.5371233177834;2702.351435655468;2704.62135301058;2713.3487962888767;2715.090444701008;485.7061477805876;487.64645631917284;497.4205051115482;498.95259950304666;499.1041081259149;585.3590318601125;588.3910152991633;601.7833579911229;623.3797371615271;632.2381530256031;8702.042775294713;8909.064548222474;8957.865632015524;9085.77285567866;9128.954355228181;483.49233305029577;493.75312343828085;588.4204059001879;591.618406128045;612.98467538311;4318.093200427883;4330.220560299176;4388.1223755249075;4466.017476155241;4469.5530446955045;114948.42525787317;115839.42080289545;116030;116386.41806790912;120685;79557.60221198972;103994;105064.47467762612;105729;105851.47074264579;88183;90358.54820725854;91877.08122918819;92748.53625731828;94044.0595594049;8089.271965523131;8113.391495637733;8302.335813134814;8384.119667435076;8695.087015863242;50573.3910556993;51350.48649513457;51679.74160129175;51816.481835182676;54541;71880.64059679669;73180.26819731735;76341;82670.58664706638;82700.58649706712;13479.797803033158;13558.593242202749;13609.659758506117;13905;13989.720205596035;15210.239488025776;15275.770863436835;15330.693386132438;15370.231488425758;15383.38466461329;13422.328883555589;13430.328483575977;13654.863451365756;14356.06685565435;14510.274486275433;13491.527796527467;13694.863051369757;13820.723585528434;13915.860841391861;14149.575512734635;13428.19430834153;13847.099938503965;13982;14350.641233969234;14466.132032078109;11963.880361196276;12338.81491777642;12344.444499997342;12806.551770687982;12817.295048772407;11396;11423.600173993871;11576.131790115944;11627.18609697347;11725.179237453296
07:37:03 INFO - PROCESS | 3296 | __end_tp_report
07:37:03 INFO - PROCESS | 3296 | __start_cc_report
07:37:03 INFO - PROCESS | 3296 | _x_x_mozilla_cycle_collect,967
07:37:03 INFO - PROCESS | 3296 | __end_cc_report
07:37:03 INFO - PROCESS | 3296 | __startTimestamp1476974223722__endTimestamp
07:37:03 INFO - PROCESS | 3296 |
07:37:03 INFO - PROCESS | 3296 | ------- Summary: start -------
07:37:03 INFO - PROCESS | 3296 | Number of tests: 6
07:37:03 INFO - PROCESS | 3296 |
07:37:03 INFO - PROCESS | 3296 | [#0] dojo.html Cycles:165 Average:4550.09 Median:4756.88 stddev:573.71 (12.1%) stddev-sans-first:556.32
07:37:03 INFO - PROCESS | 3296 | Values: 2676.4 2743.8 2749.2 2755.3 2761.0 4412.9 4630.9 4631.1 4756.0 4791.4 4616.6 4653.4 4670.0 4816.4 4829.4 4066.1 4151.8 4165.9 4172.7 4186.1 4046.3 4103.8 4131.6 4149.0 4155.4 4122.7 4185.5 4199.8 4205.8 4212.9 4021.8 4052.8 4064.1 4076.8 4123.4 4006.1 4071.0 4163.4 4163.8 4168.9 4610.0 4610.3 4704.4 4719.2 4722.2 4721.2 4745.6 4869.6 4873.0 4994.7 4873.6 4881.1 4881.5 4933.6 4970.8 4448.4 4587.1 4610.6 4616.0 4631.0 5022.8 5060.5 5088.8 5088.9 5097.6 4829.3 4859.5 4885.5 4887.7 4890.8 4894.9 4917.5 4934.2 4950.6 4954.7 4915.4 4926.3 4928.0 4929.6 4964.5 4911.5 4918.4 5027.2 5034.4 5042.0 3270.9 3312.7 3347.6 3347.9 3350.5 3030.8 3047.0 3055.5 3057.0 3074.1 4603.3 4634.5 4639.0 4649.9 4651.6 4924.2 4945.7 4965.8 4977.3 4979.1 4537.6 4641.0 4643.0 4645.5 4646.8 4153.4 4155.3 4156.1 4168.5 4168.8 4933.7 5025.2 5074.8 5080.3 5097.6 4822.2 4930.5 5042.1 5044.5 5054.3 4904.8 4915.4 4941.5 5075.4 5154.5 4816.9 4847.2 4868.3 4880.8 4959.3 4953.6 5065.9 5069.7 5073.3 5078.6 4788.1 4809.2 4851.4 4918.3 4922.2 4593.5 4751.1 4819.8 4913.7 4936.4 4433.5 4664.8 4684.8 4733.8 4744.4 4771.4 4813.0 4855.1 4877.6 4879.9 4676.6 4756.9 4758.1 4843.9 4851.7
07:37:03 INFO - PROCESS | 3296 |
07:37:03 INFO - PROCESS | 3296 | [#1] ext.html Cycles:180 Average:52071.76 Median:36317.32 stddev:102431.44 (282.0%) stddev-sans-first:102645.71
07:37:03 INFO - PROCESS | 3296 | Values: 538.4 542.4 546.0 568.8 575.6 28107.4 28348.9 28850.0 38689.6 39374.6 38750.4 39678.4 40341.4 40404.8 40478.8 8003.1 8074.6 8133.9 8137.0 8139.4 6782.6 6961.0 7939.3 8019.2 9863.1 9789.3 9838.8 10027.4 10058.8 10131.6 9825.8 10013.3 10084.9 10103.6 10108.2 6312.3 6905.7 9602.3 9725.8 10011.0 35745.6 37450.6 37860.0 37862.2 37893.8 2836.8 3158.7 3477.1 3503.5 3558.7 29253.9 29514.6 29733.1 30387.2 30427.4 3464.6 3483.5 3485.3 3508.1 3552.3 250078.0 319921.0 325395.0 326943.0 327308.0 41953.6 43458.8 48786.3 49218.3 50371.7 50581.4 50656.2 50920.5 53350.5 53797.2 13593.2 13653.6 13790.4 13806.7 13809.7 10480.8 10505.3 10532.8 10552.0 10554.0 12990.5 13359.1 14999.5 15243.8 15488.5 6270.6 6310.9 6319.7 6328.8 6400.0 710.2 732.1 774.6 790.9 868.2 25557.6 26588.2 26676.5 26849.9 27006.5 803.4 826.2 860.8 873.3 985.4 13824.0 14024.5 14130.3 14157.6 14169.4 446714.0 447035.0 447439.0 448197.0 449053.0 48075.0 48105.0 48253.5 48258.5 48273.8 920.8 978.1 1049.0 1091.2 1098.9 27949.4 28034.0 28545.7 28599.1 28658.4 40529.0 41524.0 41540.4 42067.6 42240.0 387752.0 388208.0 389044.0 389735.0 390391.0 22619.2 23159.3 23310.9 23981.9 24006.4 41741.0 41971.8 42326.0 43135.9 43148.1 34777.1 35695.1 35753.1 36765.0 38360.8 35369.0 35681.8 35869.6 36927.3 37794.1 34075.5 34331.7 34441.0 34674.1 34698.8 31629.2 31747.8 33044.3 33868.8 33968.8 24683.9 25256.9 25632.9 25678.0 25948.2
07:37:03 INFO - PROCESS | 3296 |
07:37:03 INFO - PROCESS | 3296 | [#2] jquery.html Cycles:180 Average:26734.70 Median:5151.19 stddev:71888.26 (1395.6%) stddev-sans-first:72062.47
07:37:03 INFO - PROCESS | 3296 | Values: 271.2 284.8 295.9 301.4 306.0 12529.9 16553.3 16791.9 17148.0 17713.6 16563.7 16833.4 16941.9 16990.2 17190.6 4957.7 5064.6 5235.1 5237.6 5238.8 5091.0 5183.7 5189.8 5189.9 5207.0 5092.0 5147.3 5149.5 5152.9 5167.5 4994.9 5122.0 5128.3 5142.5 5367.6 5127.3 5276.6 5283.7 5286.6 5291.2 20145.9 21384.4 21514.5 21718.7 21797.5 1652.3 1656.3 1691.9 1724.8 1727.3 8636.7 8895.7 8896.4 9066.4 9158.7 1642.0 1695.1 1700.1 1738.2 1740.4 82831.6 84463.0 87319.6 87879.0 88154.0 8922.7 9001.1 9118.2 9250.5 9260.7 11044.4 11591.3 11619.6 11622.8 11624.5 6692.7 6891.5 7027.8 7029.8 7144.1 7197.6 7210.2 7426.0 7671.5 7680.3 7720.0 7981.0 8016.1 8121.9 8144.4 3284.8 3295.3 3301.9 3311.3 3384.4 711.3 716.7 740.8 838.1 839.6 16916.9 17523.0 17681.4 17835.5 17924.3 770.9 773.6 798.3 836.8 854.5 9578.5 9718.3 9726.5 9756.8 10048.6 417926.0 429137.0 431194.0 440564.8 440686.0 90412.0 93544.5 93617.0 94265.0 94501.0 847.3 848.5 852.9 872.1 873.2 15094.5 15450.3 15458.1 15876.4 15925.2 41376.2 41568.8 42031.8 42058.6 42305.4 55621.7 55852.2 58626.7 58764.7 59445.1 7024.1 7116.2 7128.1 7268.8 7440.1 9786.5 11855.0 11920.2 12212.5 12266.9 9737.8 9992.4 10063.7 10064.1 10177.0 9992.5 10050.3 10255.0 10456.3 10563.8 10374.9 10461.8 10604.3 10621.7 10641.3 7219.1 7270.0 7370.9 7385.9 7551.5 9894.7 9934.7 10077.1 10270.2 10548.7
07:37:03 INFO - PROCESS | 3296 |
07:37:03 INFO - PROCESS | 3296 | [#3] mootools.html Cycles:180 Average:4435.02 Median:5771.09 stddev:2479.09 (43.0%) stddev-sans-first:2473.19
07:37:03 INFO - PROCESS | 3296 | Values: 1075.2 1164.8 1165.8 1245.5 1476.5 6116.4 6730.0 6913.4 6955.5 6984.5 6969.8 6972.3 7013.4 7014.3 7015.1 1870.3 1879.1 1879.5 1880.3 1884.4 1825.1 1834.5 1856.9 1857.7 1880.0 1861.3 1866.2 1882.2 1882.3 1887.2 1769.4 1800.5 1808.3 1814.1 1814.1 1745.3 1746.0 1749.9 1767.4 1771.9 6666.4 6691.8 6719.8 6760.1 6781.6 4062.2 4154.2 4158.8 4159.1 4159.4 2701.0 2757.0 2794.9 2796.6 2798.1 845.0 863.1 878.9 880.4 881.0 8785.2 8839.2 8851.2 8872.0 8903.7 3414.4 3435.8 3485.7 3509.0 3515.7 7072.5 7124.4 7150.6 7155.4 7168.6 4004.1 4047.1 4061.1 4061.5 4065.6 3369.2 3466.7 3845.0 3846.2 3850.3 2886.9 2948.0 2976.4 2983.3 2999.6 885.2 887.8 892.8 893.0 894.6 706.1 726.7 727.4 731.4 750.8 4369.9 4403.1 4418.7 4473.4 4546.3 707.8 735.4 738.3 743.1 759.9 2997.7 3057.0 3059.0 3084.6 3120.9 8097.4 8213.6 8254.1 8359.4 8360.9 8205.5 8301.4 8302.2 8308.8 8318.3 6516.2 6545.2 6560.9 6584.7 6608.5 4301.9 4378.5 4405.7 4453.5 4458.9 7630.7 7822.3 7843.2 7859.6 7873.5 7456.2 7761.0 7965.5 7972.7 7981.7 5685.9 5973.4 6042.5 6139.2 6152.2 6063.8 6124.6 6473.8 6656.4 6740.2 5782.2 6064.4 6188.4 6259.3 6294.2 5449.4 5895.4 5979.9 6053.6 6096.4 5232.2 5696.2 5753.2 5768.0 5774.1 3723.6 3800.5 3820.2 3969.9 4001.7 3841.9 3884.5 3972.8 3983.3 4121.9
07:37:03 INFO - PROCESS | 3296 |
07:37:03 INFO - PROCESS | 3296 | [#4] prototype.html Cycles:180 Average:4379.56 Median:5100.24 stddev:1382.77 (27.1%) stddev-sans-first:1351.27
07:37:03 INFO - PROCESS | 3296 | Values: 239.2 242.4 248.3 249.3 249.8 5050.9 5139.2 5147.0 5149.6 5162.1 4847.7 4914.4 4959.5 4969.5 4978.1 3611.8 3639.9 3747.4 3748.2 3758.3 3751.6 3753.5 3757.3 3758.3 3760.2 3715.7 3722.2 3725.4 3728.7 3732.0 3497.8 3593.1 3601.9 3682.5 3703.4 3139.9 3218.4 3220.7 3221.5 3234.8 4880.9 4901.5 4925.9 4928.2 4936.5 4203.2 4355.0 4363.8 4363.8 4368.8 5006.5 5187.7 5215.2 5278.4 5280.4 3425.7 3467.2 3468.6 3475.1 3475.7 5514.6 5523.8 5541.9 5553.1 5557.4 3492.3 3539.3 3546.8 3549.4 3552.4 3562.5 3563.4 3594.3 3598.2 3598.8 4036.4 4219.2 4244.4 4263.2 4274.3 5366.3 5375.8 5380.5 5382.9 5386.6 1231.2 1240.8 1243.9 1252.4 1255.2 739.7 756.8 767.9 775.1 781.9 4902.7 4969.9 5076.3 5082.3 5094.9 5494.1 5512.8 5534.3 5535.6 5540.7 5083.4 5083.5 5084.5 5089.3 5105.6 2782.9 2862.8 2891.9 2909.5 2938.4 5644.7 5656.5 5661.5 5664.7 5671.9 5517.3 5524.1 5584.6 5593.7 5604.9 5460.0 5657.5 5661.5 5662.6 5671.4 5456.3 5468.3 5480.1 5498.4 5503.0 5325.2 5394.8 5404.3 5411.3 5412.7 5640.4 5661.4 5678.6 5693.4 5695.5 3358.2 3555.6 3558.0 3563.7 3579.8 5402.4 5410.9 5434.6 5469.0 5483.5 5317.2 5318.7 5327.1 5328.5 5338.4 5133.4 5298.5 5306.3 5327.5 5342.3 5190.2 5198.8 5201.8 5212.9 5217.6 5135.0 5290.6 5298.8 5327.0 5330.0 5144.8 5187.6 5264.6 5288.7 5300.3
07:37:03 INFO - PROCESS | 3296 |
07:37:03 INFO - PROCESS | 3296 | [#5] yui.html Cycles:180 Average:20994.08 Median:2228.65 stddev:31013.74 (1391.6%) stddev-sans-first:31063.00
07:37:03 INFO - PROCESS | 3296 | Values: 615.3 653.6 654.3 686.7 689.6 13874.9 15114.5 15174.5 15188.4 15200.2 13551.0 13680.0 13834.9 13870.4 13874.9 505.3 515.5 595.2 618.7 623.1 511.0 515.0 515.7 602.9 616.7 654.7 655.5 655.9 659.4 660.4 633.7 639.8 648.2 652.6 655.7 384.0 388.8 459.2 460.7 476.7 13504.4 13825.9 13952.4 14056.9 14068.3 12837.0 12839.5 13010.8 13149.9 13401.5 12802.5 12883.4 13172.5 13289.5 13302.3 4068.5 4089.0 4220.1 4254.2 4258.2 78063.2 79753.6 79964.0 82314.6 82715.6 10262.7 10360.2 10441.8 10535.3 10635.4 19210.4 19272.4 19283.9 19288.6 19296.0 6895.3 6990.4 7080.5 7105.5 7157.1 3611.6 3699.9 3729.3 3756.3 3855.6 2571.5 2702.4 2704.6 2713.3 2715.1 485.7 487.6 497.4 499.0 499.1 585.4 588.4 601.8 623.4 632.2 8702.0 8909.1 8957.9 9085.8 9129.0 483.5 493.8 588.4 591.6 613.0 4318.1 4330.2 4388.1 4466.0 4469.6 114948.4 115839.4 116030.0 116386.4 120685.0 79557.6 103994.0 105064.5 105729.0 105851.5 88183.0 90358.5 91877.1 92748.5 94044.1 8089.3 8113.4 8302.3 8384.1 8695.1 50573.4 51350.5 51679.7 51816.5 54541.0 71880.6 73180.3 76341.0 82670.6 82700.6 13479.8 13558.6 13609.7 13905.0 13989.7 15210.2 15275.8 15330.7 15370.2 15383.4 13422.3 13430.3 13654.9 14356.1 14510.3 13491.5 13694.9 13820.7 13915.9 14149.6 13428.2 13847.1 13982.0 14350.6 14466.1 11963.9 12338.8 12344.4 12806.6 12817.3 11396.0 11423.6 11576.1 11627.2 11725.2
07:37:03 INFO - PROCESS | 3296 | -------- Summary: end --------
07:37:03 INFO - PROCESS | 3296 |
07:37:04 INFO - TEST-INFO | 3296: exit 0
07:37:05 INFO - TEST-OK | dromaeo_css | took 1120553ms
07:37:05 INFO - TEST-START | kraken
07:37:05 INFO - operating with platform_type : w7_
07:37:05 INFO - Initialising browser for kraken test...
07:37:05 INFO - TEST-INFO | started process 3712 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpev6pqv\profile http://localhost:49567/getInfo.html)
07:37:09 INFO - PROCESS | 3712 | __metrics Screen width/height:1600/1200
07:37:09 INFO - PROCESS | 3712 | colorDepth:24
07:37:09 INFO - PROCESS | 3712 | Browser inner width/height: 1010/674
07:37:09 INFO - PROCESS | 3712 | __metrics
07:37:10 INFO - PROCESS | 3712 | 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:37:10 INFO - PROCESS | 3712 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
07:37:10 INFO - PROCESS | 3712 | console.error:
07:37:10 INFO - PROCESS | 3712 | Message: Error: Connection closed before committing the transaction.
07:37:10 INFO - PROCESS | 3712 | Stack:
07:37:10 INFO - PROCESS | 3712 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:937:23
07:37:10 INFO - PROCESS | 3712 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7
07:37:10 INFO - PROCESS | 3712 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11
07:37:10 INFO - PROCESS | 3712 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7
07:37:10 INFO - PROCESS | 3712 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7
07:37:10 INFO - PROCESS | 3712 | TaskImpl_run@resource://gre/modules/Task.jsm:327:15
07:37:10 INFO - PROCESS | 3712 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9
07:37:10 INFO - PROCESS | 3712 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15
07:37:10 INFO - PROCESS | 3712 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5
07:37:10 INFO - PROCESS | 3712 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29
07:37:10 INFO - PROCESS | 3712 | Barrier/this.client.addBlocker/promise13.1%
07:38:07 INFO - TinderboxPrint: I/O read bytes / time
187,672,576 / 2,102,860
07:38:07 INFO - TinderboxPrint: I/O write bytes / time
462,715,392 / 4,008,820
07:38:07 INFO - TinderboxPrint: CPU idle
8,257.0 (86.9%)
07:38:07 INFO - TinderboxPrint: CPU user
1,209.7 (12.7%)
07:38:07 INFO - install - Wall time: 3s; CPU: 11.0%; Read bytes: 0; Write bytes: 34667008; Read time: 0; Write time: 38140
07:38:07 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 187672576; Write bytes: 391172608; Read time: 2102860; Write time: 3677680
07:38:08 INFO - Running post-run listener: _upload_blobber_files
07:38:08 INFO - Blob upload gear active.
07:38:08 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir.
07:38:08 INFO - Files from C:\slave\test\build\blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com
07:38:08 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\venv\\Scripts\\blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', 'C:\\slave\\test\\oauth.txt', '-b', 'Fx-Team', '-d', 'C:\\slave\\test\\build\\blobber_upload_dir', '--output-manifest', 'C:\\slave\\test\\build\\uploaded_files.json']
07:38:08 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\venv\Scripts\blobberc.py -u https://blobupload.elasticbeanstalk.com -a C:\slave\test\oauth.txt -b Fx-Team -d C:\slave\test\build\blobber_upload_dir --output-manifest C:\slave\test\build\uploaded_files.json
07:38:08 INFO - (blobuploader) - INFO - Open directory for files ...
07:38:08 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log ...
07:38:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
07:38:08 INFO - (blobuploader) - INFO - Uploading, attempt #1.
07:38:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded
07:38:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
07:38:09 INFO - (blobuploader) - INFO - Done attempting.
07:38:09 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log ...
07:38:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
07:38:09 INFO - (blobuploader) - INFO - Uploading, attempt #1.
07:38:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded
07:38:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
07:38:11 INFO - (blobuploader) - INFO - Done attempting.
07:38:11 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ...
07:38:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
07:38:11 INFO - (blobuploader) - INFO - Uploading, attempt #1.
07:38:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
07:38:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
07:38:20 INFO - (blobuploader) - INFO - Done attempting.
07:38:20 INFO - (blobuploader) - INFO - Iteration through files over.
07:38:20 INFO - Return code: 0
07:38:20 INFO - rmtree: C:\slave\test\build\uploaded_files.json
07:38:20 INFO - Using _rmtree_windows ...
07:38:20 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1
07:38:20 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/067aee7e6ff1c096cf2f414dd7a0011e12216d439a8a678cfdacbfe42b5703603e41e3e33ec774e421bb2381ef7a69141cf3045fe74e8c85adb4fc7f60b3bcb0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b9ed3df1ac3b66efedbb3dab55e353ac62ed8e69c5d168577f3b516b51289ff8fe0a2e1f0342a55cf8d544ef34f3e05ec3cdf6850c602d82ecd1b60522d8083a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/89955eda245ccdd04af3cc08b05404c9f5875e0908b135bb8170201ba12750ba0b9640b23d3c6b8ca38d04fee15e3ae16f5b6144c37a78d188fe92a460d86d1a"}
07:38:20 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files
07:38:20 INFO - Writing to file C:\slave\test\properties\blobber_files
07:38:20 INFO - Contents:
07:38:20 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/067aee7e6ff1c096cf2f414dd7a0011e12216d439a8a678cfdacbfe42b5703603e41e3e33ec774e421bb2381ef7a69141cf3045fe74e8c85adb4fc7f60b3bcb0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b9ed3df1ac3b66efedbb3dab55e353ac62ed8e69c5d168577f3b516b51289ff8fe0a2e1f0342a55cf8d544ef34f3e05ec3cdf6850c602d82ecd1b60522d8083a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/89955eda245ccdd04af3cc08b05404c9f5875e0908b135bb8170201ba12750ba0b9640b23d3c6b8ca38d04fee15e3ae16f5b6144c37a78d188fe92a460d86d1a"}
07:38:20 INFO - Running post-run listener: copy_logs_to_upload_dir
07:38:20 INFO - Copying logs to upload dir...
07:38:20 INFO - mkdir: C:\slave\test\build\upload\logs
07:38:20 INFO - Copying logs to upload dir...
07:38:20 INFO - Using _rmtree_windows ...
07:38:20 INFO - Using _rmtree_windows ...
07:38:20 INFO - Using _rmtree_windows ...
07:38:20 INFO - Using _rmtree_windows ...
07:38:20 INFO - Using _rmtree_windows ...
07:38:20 INFO - Using _rmtree_windows ...
07:38:20 INFO - Using _rmtree_windows ...
program finished with exit code 0
elapsedTime=1393.712000
========= master_lag: 0.81 =========
========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 14 secs) (at 2016-10-20 07:38:20.582624) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-20 07:38:20.583278) =========
'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-046
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-046
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-046
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/067aee7e6ff1c096cf2f414dd7a0011e12216d439a8a678cfdacbfe42b5703603e41e3e33ec774e421bb2381ef7a69141cf3045fe74e8c85adb4fc7f60b3bcb0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b9ed3df1ac3b66efedbb3dab55e353ac62ed8e69c5d168577f3b516b51289ff8fe0a2e1f0342a55cf8d544ef34f3e05ec3cdf6850c602d82ecd1b60522d8083a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/89955eda245ccdd04af3cc08b05404c9f5875e0908b135bb8170201ba12750ba0b9640b23d3c6b8ca38d04fee15e3ae16f5b6144c37a78d188fe92a460d86d1a"}
build_url:https://queue.taskcluster.net/v1/task/WAAtoKMpTeeonEirK7BiOQ/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
program finished with exit code 0
elapsedTime=0.101000
build_url: 'https://queue.taskcluster.net/v1/task/WAAtoKMpTeeonEirK7BiOQ/artifacts/public/build/firefox-52.0a1.en-US.win32.zip'
blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/067aee7e6ff1c096cf2f414dd7a0011e12216d439a8a678cfdacbfe42b5703603e41e3e33ec774e421bb2381ef7a69141cf3045fe74e8c85adb4fc7f60b3bcb0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b9ed3df1ac3b66efedbb3dab55e353ac62ed8e69c5d168577f3b516b51289ff8fe0a2e1f0342a55cf8d544ef34f3e05ec3cdf6850c602d82ecd1b60522d8083a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/89955eda245ccdd04af3cc08b05404c9f5875e0908b135bb8170201ba12750ba0b9640b23d3c6b8ca38d04fee15e3ae16f5b6144c37a78d188fe92a460d86d1a"}'
========= master_lag: 0.01 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-20 07:38:20.697089) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 07:38:20.697431) =========
'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-046
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-046
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-046
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 07:38:20.808196) =========
========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-10-20 07:38:20.808510) =========
========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-10-20 07:38:21.372828) =========
========= Total master_lag: 1.00 =========