builder: autoland_win7_ix_test-dromaeojs-pgo
slave: t-w732-ix-039
starttime: 1474864744.73
results: success (0)
buildid: 20160925183005
builduid: 51c5586456b241d69da6b6d1363c1d3d
revision: 41e054b0c9635c17ee91374e0d774ae3daf492b7
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-25 21:39:04.730808) =========
master: http://buildbot-master126.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-25 21:39:04.731681) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-25 21:39:04.732213) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-25 21:39:04.824719) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-25 21:39:04.825101) =========
'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-039
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-039
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-039
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
--21:39:05-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
=> `archiver_client.py'
Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102
Connecting to hg.mozilla.org|63.245.215.25|:443... connected.
HTTP request sent, awaiting response... 200 Script output follows
Length: 12,141 (12K) [text/x-python]
0K .......... . 100% 33.03 MB/s
21:39:06 (33.03 MB/s) - `archiver_client.py' saved [12141/12141]
program finished with exit code 0
elapsedTime=1.163000
========= master_lag: 0.08 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-25 21:39:06.066025) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 9 secs) (at 2016-09-25 21:39:06.066357) =========
'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-039
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-039
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-039
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=9.800000
========= master_lag: 0.01 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 9 secs) (at 2016-09-25 21:39:15.879287) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-25 21:39:15.880141) =========
'bash' '-c' u'python archiver_client.py mozharness --repo integration/autoland --rev 41e054b0c9635c17ee91374e0d774ae3daf492b7 --destination scripts --debug'
in dir C:\slave\test\. (timeout 1200 secs)
watching logfiles {}
argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 41e054b0c9635c17ee91374e0d774ae3daf492b7 --destination scripts --debug']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-039
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-039
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-039
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-09-25 21:39:16,141 truncating revision to first 12 chars
2016-09-25 21:39:16,142 Setting DEBUG logging.
2016-09-25 21:39:16,142 attempt 1/10
2016-09-25 21:39:16,142 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/41e054b0c963?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2016-09-25 21:39:17,066 unpacking tar archive at: autoland-41e054b0c963/testing/mozharness/
2016-09-25 21:39:17,213 skipping symlink on windows: configs/single_locale/linux32.py
program finished with exit code 0
elapsedTime=1.595000
========= master_lag: 0.08 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-25 21:39:17.551729) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-25 21:39:17.552126) =========
script_repo_revision: 41e054b0c9635c17ee91374e0d774ae3daf492b7
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-25 21:39:17.552521) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-25 21:39:17.552845) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-25 21:39:17.563924) =========
========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 34 secs) (at 2016-09-25 21:39:17.564384) =========
'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Autoland' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Autoland'
in dir C:\slave\test\. (timeout 3600 secs) (maxTime 7200 secs)
watching logfiles {}
argv: ['c:/mozilla-build/python27/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-039
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-039
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-039
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
21:39:18 INFO - MultiFileLogger online at 20160925 21:39:18 in C:\slave\test
21:39:18 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland
21:39:18 INFO - Dumping config to C:\slave\test\logs\localconfig.json.
21:39:18 INFO - {'append_to_log': False,
21:39:18 INFO - 'base_work_dir': 'C:\\slave\\test',
21:39:18 INFO - 'blob_upload_branch': 'Autoland',
21:39:18 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt',
21:39:18 INFO - 'branch': 'Autoland',
21:39:18 INFO - 'buildbot_json_path': 'buildprops.json',
21:39:18 INFO - 'config_files': ('talos/windows_config.py',),
21:39:18 INFO - 'default_actions': ('clobber',
21:39:18 INFO - 'read-buildbot-config',
21:39:18 INFO - 'download-and-extract',
21:39:18 INFO - 'populate-webroot',
21:39:18 INFO - 'create-virtualenv',
21:39:18 INFO - 'install',
21:39:18 INFO - 'run-tests'),
21:39:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
21:39:18 INFO - 'download_minidump_stackwalk': True,
21:39:18 INFO - 'download_symbols': 'ondemand',
21:39:18 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python',
21:39:18 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'),
21:39:18 INFO - 'hg': 'c:/mozilla-build/hg/hg',
21:39:18 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python',
21:39:18 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'),
21:39:18 INFO - 'python': 'c:/mozilla-build/python27/python.exe',
21:39:18 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe',
21:39:18 INFO - 'C:/mozilla-build/tooltool.py'),
21:39:18 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe',
21:39:18 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')},
21:39:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
21:39:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
21:39:18 INFO - 'installer_path': 'installer.exe',
21:39:18 INFO - 'log_level': 'info',
21:39:18 INFO - 'log_name': 'talos',
21:39:18 INFO - 'log_to_console': True,
21:39:18 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe',
21:39:18 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe',
21:39:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest',
21:39:18 INFO - 'opt_config_files': (),
21:39:18 INFO - 'pip_index': False,
21:39:18 INFO - 'sps_profile': False,
21:39:18 INFO - 'sps_profile_interval': 0,
21:39:18 INFO - 'suite': 'dromaeojs',
21:39:18 INFO - 'system_bits': '32',
21:39:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
21:39:18 INFO - 'title': 'T-W732-IX-039',
21:39:18 INFO - 'use_talos_json': True,
21:39:18 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'),
21:39:18 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv',
21:39:18 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll',
21:39:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
21:39:18 INFO - 'work_dir': 'build'}
21:39:18 INFO - [mozharness: 2016-09-26 04:39:18.121000Z] Running clobber step.
21:39:18 INFO - Running pre-action listener: _resource_record_pre_action
21:39:18 INFO - Running main action method: clobber
21:39:18 INFO - rmtree: C:\slave\test\build
21:39:18 INFO - Using _rmtree_windows ...
21:39:18 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1
21:41:05 INFO - Running post-action listener: _resource_record_post_action
21:41:05 INFO - [mozharness: 2016-09-26 04:41:05.700000Z] Finished clobber step (success)
21:41:05 INFO - [mozharness: 2016-09-26 04:41:05.700000Z] Running read-buildbot-config step.
21:41:05 INFO - Running pre-action listener: _resource_record_pre_action
21:41:05 INFO - Running main action method: read_buildbot_config
21:41:05 INFO - Using buildbot properties:
21:41:05 INFO - {
21:41:05 INFO - "project": "",
21:41:05 INFO - "product": "firefox",
21:41:05 INFO - "script_repo_revision": "production",
21:41:05 INFO - "builddir": "autoland_win7_ix_test-dromaeojs",
21:41:05 INFO - "repository": "",
21:41:05 INFO - "buildername": "Windows 7 32-bit autoland pgo talos dromaeojs",
21:41:05 INFO - "buildid": "20160925183005",
21:41:05 INFO - "pgo_build": "True",
21:41:05 INFO - "basedir": "C:\\slave\\test",
21:41:05 INFO - "buildnumber": 126,
21:41:05 INFO - "slavename": "t-w732-ix-039",
21:41:05 INFO - "master": "http://buildbot-master126.bb.releng.scl3.mozilla.com:8201/",
21:41:05 INFO - "platform": "win7_ix",
21:41:05 INFO - "scheduler": "tests-autoland-win32-pgo-talos",
21:41:05 INFO - "branch": "autoland",
21:41:05 INFO - "repo_path": "integration/autoland",
21:41:05 INFO - "revision": "41e054b0c9635c17ee91374e0d774ae3daf492b7",
21:41:05 INFO - "stage_platform": "win32-pgo",
21:41:05 INFO - "builduid": "51c5586456b241d69da6b6d1363c1d3d",
21:41:05 INFO - "slavebuilddir": "test"
21:41:05 INFO - }
21:41:05 INFO - Found installer url https://queue.taskcluster.net/v1/task/J4XRUAtCR3qHKk2_hM0uVA/artifacts/public/build/firefox-52.0a1.en-US.win32.zip.
21:41:05 INFO - Running post-action listener: _resource_record_post_action
21:41:05 INFO - [mozharness: 2016-09-26 04:41:05.703000Z] Finished read-buildbot-config step (success)
21:41:05 INFO - [mozharness: 2016-09-26 04:41:05.704000Z] Running download-and-extract step.
21:41:05 INFO - Running pre-action listener: _resource_record_pre_action
21:41:05 INFO - Running main action method: download_and_extract
21:41:05 INFO - mkdir: C:\slave\test\build\tests
21:41:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
21:41:05 INFO - trying https://queue.taskcluster.net/v1/task/J4XRUAtCR3qHKk2_hM0uVA/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json
21:41:05 INFO - Downloading https://queue.taskcluster.net/v1/task/J4XRUAtCR3qHKk2_hM0uVA/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
21:41:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J4XRUAtCR3qHKk2_hM0uVA/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
21:41:06 INFO - Downloaded 1218 bytes.
21:41:06 INFO - Reading from file C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json
21:41:06 INFO - Using the following test package requirements:
21:41:06 INFO - {u'common': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
21:41:06 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
21:41:06 INFO - u'firefox-52.0a1.en-US.win32.cppunittest.tests.zip'],
21:41:06 INFO - u'gtest': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
21:41:06 INFO - u'jittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
21:41:06 INFO - u'jsshell-win32.zip'],
21:41:06 INFO - u'mochitest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
21:41:06 INFO - u'firefox-52.0a1.en-US.win32.mochitest.tests.zip'],
21:41:06 INFO - u'mozbase': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
21:41:06 INFO - u'reftest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
21:41:06 INFO - u'firefox-52.0a1.en-US.win32.reftest.tests.zip'],
21:41:06 INFO - u'talos': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
21:41:06 INFO - u'firefox-52.0a1.en-US.win32.talos.tests.zip'],
21:41:06 INFO - u'web-platform': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
21:41:06 INFO - u'firefox-52.0a1.en-US.win32.web-platform.tests.zip'],
21:41:06 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
21:41:06 INFO - u'firefox-52.0a1.en-US.win32.xpcshell.tests.zip']}
21:41:06 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip'] for test suite category: common
21:41:06 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/J4XRUAtCR3qHKk2_hM0uVA/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip
21:41:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J4XRUAtCR3qHKk2_hM0uVA/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1
21:41:06 INFO - Fetch https://queue.taskcluster.net/v1/task/J4XRUAtCR3qHKk2_hM0uVA/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory
21:41:07 INFO - Expected file size: 17291479
21:41:39 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
21:41:39 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/J4XRUAtCR3qHKk2_hM0uVA/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip
21:41:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J4XRUAtCR3qHKk2_hM0uVA/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1
21:41:39 INFO - Fetch https://queue.taskcluster.net/v1/task/J4XRUAtCR3qHKk2_hM0uVA/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory
21:41:40 INFO - Expected file size: 17291479
21:41:50 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/J4XRUAtCR3qHKk2_hM0uVA/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip
21:41:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J4XRUAtCR3qHKk2_hM0uVA/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip'}, attempt #1
21:41:50 INFO - Fetch https://queue.taskcluster.net/v1/task/J4XRUAtCR3qHKk2_hM0uVA/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip into memory
21:41:51 INFO - Expected file size: 14414059
21:42:01 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')]}
21:42:01 INFO - trying https://queue.taskcluster.net/v1/task/J4XRUAtCR3qHKk2_hM0uVA/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
21:42:01 INFO - Downloading https://queue.taskcluster.net/v1/task/J4XRUAtCR3qHKk2_hM0uVA/artifacts/public/build/firefox-52.0a1.en-US.win32.zip to C:\slave\test\build\firefox-52.0a1.en-US.win32.zip
21:42:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J4XRUAtCR3qHKk2_hM0uVA/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
21:42:03 INFO - Downloaded 54758726 bytes.
21:42:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/J4XRUAtCR3qHKk2_hM0uVA/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
21:42:03 INFO - mkdir: C:\slave\test\properties
21:42:03 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url
21:42:03 INFO - Writing to file C:\slave\test\properties\build_url
21:42:03 INFO - Contents:
21:42:03 INFO - build_url:https://queue.taskcluster.net/v1/task/J4XRUAtCR3qHKk2_hM0uVA/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
21:42:04 INFO - Running post-action listener: _resource_record_post_action
21:42:04 INFO - Running post-action listener: set_extra_try_arguments
21:42:04 INFO - [mozharness: 2016-09-26 04:42:04.524000Z] Finished download-and-extract step (success)
21:42:04 INFO - [mozharness: 2016-09-26 04:42:04.525000Z] Running populate-webroot step.
21:42:04 INFO - Running pre-action listener: _resource_record_pre_action
21:42:04 INFO - Running main action method: populate_webroot
21:42:04 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']},
21:42:04 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']},
21:42:04 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome',
21:42:04 INFO - u'--tppagecycles',
21:42:04 INFO - u'7'],
21:42:04 INFO - u'tests': [u'tsvgm']}},
21:42:04 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'],
21:42:04 INFO - u'tests': [u'tresize', u'tcanvasmark']},
21:42:04 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']},
21:42:04 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'],
21:42:04 INFO - u'tests': [u'dromaeo_css', u'kraken']},
21:42:04 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']},
21:42:04 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
21:42:04 INFO - u'talos_options': [u'--disable-e10s'],
21:42:04 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
21:42:04 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
21:42:04 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
21:42:04 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
21:42:04 INFO - u'talos_options': [u'--disable-e10s'],
21:42:04 INFO - u'tests': [u'damp', u'tps']},
21:42:04 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
21:42:04 INFO - u'tests': [u'damp', u'tps']},
21:42:04 INFO - u'g3': {u'talos_options': [u'--disable-e10s'],
21:42:04 INFO - u'tests': [u'dromaeo_dom']},
21:42:04 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']},
21:42:04 INFO - u'g4': {u'talos_options': [u'--disable-e10s'],
21:42:04 INFO - u'tests': [u'basic_compositor_video']},
21:42:04 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']},
21:42:04 INFO - u'other': {u'talos_options': [u'--disable-e10s'],
21:42:04 INFO - u'tests': [u'a11yr',
21:42:04 INFO - u'ts_paint',
21:42:04 INFO - u'tpaint',
21:42:04 INFO - u'sessionrestore',
21:42:04 INFO - u'sessionrestore_no_auto_restore',
21:42:04 INFO - u'tabpaint']},
21:42:04 INFO - u'other-e10s': {u'tests': [u'a11yr',
21:42:04 INFO - u'ts_paint',
21:42:04 INFO - u'tpaint',
21:42:04 INFO - u'sessionrestore',
21:42:04 INFO - u'sessionrestore_no_auto_restore',
21:42:04 INFO - u'tabpaint']},
21:42:04 INFO - u'other-e10s_l64': {u'tests': [u'a11yr',
21:42:04 INFO - u'ts_paint',
21:42:04 INFO - u'tpaint',
21:42:04 INFO - u'sessionrestore',
21:42:04 INFO - u'sessionrestore_no_auto_restore',
21:42:04 INFO - u'tabpaint']},
21:42:04 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr',
21:42:04 INFO - u'ts_paint',
21:42:04 INFO - u'tpaint',
21:42:04 INFO - u'sessionrestore',
21:42:04 INFO - u'sessionrestore_no_auto_restore',
21:42:04 INFO - u'tabpaint']},
21:42:04 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'],
21:42:04 INFO - u'tests': [u'a11yr',
21:42:04 INFO - u'ts_paint',
21:42:04 INFO - u'tpaint',
21:42:04 INFO - u'sessionrestore',
21:42:04 INFO - u'sessionrestore_no_auto_restore',
21:42:04 INFO - u'tabpaint']},
21:42:04 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'],
21:42:04 INFO - u'tests': [u'a11yr',
21:42:04 INFO - u'ts_paint',
21:42:04 INFO - u'tpaint',
21:42:04 INFO - u'sessionrestore',
21:42:04 INFO - u'sessionrestore_no_auto_restore',
21:42:04 INFO - u'tabpaint']},
21:42:04 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'],
21:42:04 INFO - u'tests': [u'tsvgx',
21:42:04 INFO - u'tsvgr_opacity',
21:42:04 INFO - u'tart',
21:42:04 INFO - u'tscrollx',
21:42:04 INFO - u'cart']},
21:42:04 INFO - u'svgr-e10s': {u'tests': [u'tsvgx',
21:42:04 INFO - u'tsvgr_opacity',
21:42:04 INFO - u'tart',
21:42:04 INFO - u'tscrollx',
21:42:04 INFO - u'cart']},
21:42:04 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
21:42:04 INFO - u'talos_options': [u'--disable-e10s'],
21:42:04 INFO - u'tests': [u'tp5o']},
21:42:04 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
21:42:04 INFO - u'tests': [u'tp5o']},
21:42:04 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
21:42:04 INFO - u'talos_options': [u'--disable-e10s',
21:42:04 INFO - u'--xperf_path',
21:42:04 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
21:42:04 INFO - u'tests': [u'tp5n']},
21:42:04 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
21:42:04 INFO - u'talos_options': [u'--xperf_path',
21:42:04 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
21:42:04 INFO - u'tests': [u'tp5n']}},
21:42:04 INFO - u'talos.zip': {u'path': u'',
21:42:04 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}}
21:42:04 INFO - Running post-action listener: _resource_record_post_action
21:42:04 INFO - [mozharness: 2016-09-26 04:42:04.578000Z] Finished populate-webroot step (success)
21:42:04 INFO - [mozharness: 2016-09-26 04:42:04.578000Z] Running create-virtualenv step.
21:42:04 INFO - Running pre-action listener: _resource_record_pre_action
21:42:04 INFO - Running main action method: create_virtualenv
21:42:04 INFO - Creating virtualenv C:\slave\test\build/venv
21:42:04 INFO - mkdir: C:\slave\test\build\venv\Scripts
21:42:04 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll
21:42:04 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'!
21:42:04 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
21:42:04 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv
21:42:06 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe
21:42:10 INFO - Installing distribute......................................................................................................................................................................................done.
21:42:16 INFO - Return code: 0
21:42:16 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version']
21:42:16 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version
21:42:16 INFO - Reading from file tmpfile_stdout
21:42:16 INFO - Output received:
21:42:16 INFO - pip 0.8.2 from c:\slave\test\build\venv\lib\site-packages\pip-0.8.2-py2.7.egg (python 2.7)
21:42:16 INFO - Using _rmtree_windows ...
21:42:16 INFO - Using _rmtree_windows ...
21:42:16 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv
21:42:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
21:42:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
21:42:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
21:42:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
21:42:16 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018A1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01945E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0184B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01952E90>, '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 0x0136A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A14520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-039', 'USERDOMAIN': 'T-W732-IX-039', '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-039', '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
21:42:16 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in C:\slave\test\build
21:42:16 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5
21:42:16 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
21:42:16 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
21:42:16 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
21:42:16 INFO - 'COMPUTERNAME': 'T-W732-IX-039',
21:42:16 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
21:42:16 INFO - 'CYGWINBASE': 'C:\\cygwin',
21:42:16 INFO - 'DCLOCATION': 'SCL3',
21:42:16 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
21:42:16 INFO - 'FP_NO_HOST_CHECK': 'NO',
21:42:16 INFO - 'HOMEDRIVE': 'C:',
21:42:16 INFO - 'HOMEPATH': '\\Users\\cltbld',
21:42:16 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
21:42:16 INFO - 'KTS_VERSION': '1.19c',
21:42:16 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
21:42:16 INFO - 'LOGONSERVER': '\\\\T-W732-IX-039',
21:42:16 INFO - 'MONDIR': 'C:\\Monitor_config\\',
21:42:16 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
21:42:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
21:42:16 INFO - 'MOZ_NO_REMOTE': '1',
21:42:16 INFO - 'NO_EM_RESTART': '1',
21:42:16 INFO - 'NUMBER_OF_PROCESSORS': '8',
21:42:16 INFO - 'OS': 'Windows_NT',
21:42:16 INFO - 'OURDRIVE': 'C:',
21:42:16 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
21:42:16 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
21:42:16 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
21:42:16 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
21:42:16 INFO - 'PROCESSOR_LEVEL': '6',
21:42:16 INFO - 'PROCESSOR_REVISION': '1e05',
21:42:16 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
21:42:16 INFO - 'PROGRAMFILES': 'C:\\Program Files',
21:42:16 INFO - 'PROMPT': '$P$G',
21:42:16 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
21:42:16 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
21:42:16 INFO - 'PUBLIC': 'C:\\Users\\Public',
21:42:16 INFO - 'PWD': 'C:\\slave\\test',
21:42:16 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
21:42:16 INFO - 'SLAVEDIR': 'C:\\slave\\',
21:42:16 INFO - 'SYSTEMDRIVE': 'C:',
21:42:16 INFO - 'SYSTEMROOT': 'C:\\windows',
21:42:16 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:42:16 INFO - 'TEST1': 'testie',
21:42:16 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:42:16 INFO - 'USERDOMAIN': 'T-W732-IX-039',
21:42:16 INFO - 'USERNAME': 'cltbld',
21:42:16 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
21:42:16 INFO - 'WINDIR': 'C:\\windows',
21:42:16 INFO - 'WINDOWS_TRACING_FLAGS': '3',
21:42:16 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
21:42:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
21:42:17 INFO - Ignoring indexes: http://pypi.python.org/simple/
21:42:17 INFO - Downloading/unpacking pip>=1.5
21:42:21 INFO - Running setup.py egg_info for package pip
21:42:21 INFO - warning: no files found matching 'pip\cacert.pem'
21:42:21 INFO - warning: no files found matching '*.html' under directory 'docs'
21:42:21 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
21:42:21 INFO - no previously-included directories found matching 'docs\_build\_sources'
21:42:21 INFO - Installing collected packages: pip
21:42:21 INFO - Found existing installation: pip 0.8.2
21:42:21 INFO - Uninstalling pip:
21:42:21 INFO - Successfully uninstalled pip
21:42:21 INFO - Running setup.py install for pip
21:42:21 INFO - warning: no files found matching 'pip\cacert.pem'
21:42:21 INFO - warning: no files found matching '*.html' under directory 'docs'
21:42:21 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
21:42:21 INFO - no previously-included directories found matching 'docs\_build\_sources'
21:42:21 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts
21:42:21 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts
21:42:21 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts
21:42:21 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts
21:42:21 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts
21:42:21 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts
21:42:21 INFO - Successfully installed pip
21:42:21 INFO - Cleaning up...
21:42:21 INFO - Return code: 0
21:42:21 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv
21:42:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
21:42:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
21:42:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
21:42:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
21:42:21 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018A1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01945E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0184B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01952E90>, '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 0x0136A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A14520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-039', 'USERDOMAIN': 'T-W732-IX-039', '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-039', '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
21:42:22 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
21:42:22 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
21:42:22 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
21:42:22 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
21:42:22 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
21:42:22 INFO - 'COMPUTERNAME': 'T-W732-IX-039',
21:42:22 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
21:42:22 INFO - 'CYGWINBASE': 'C:\\cygwin',
21:42:22 INFO - 'DCLOCATION': 'SCL3',
21:42:22 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
21:42:22 INFO - 'FP_NO_HOST_CHECK': 'NO',
21:42:22 INFO - 'HOMEDRIVE': 'C:',
21:42:22 INFO - 'HOMEPATH': '\\Users\\cltbld',
21:42:22 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
21:42:22 INFO - 'KTS_VERSION': '1.19c',
21:42:22 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
21:42:22 INFO - 'LOGONSERVER': '\\\\T-W732-IX-039',
21:42:22 INFO - 'MONDIR': 'C:\\Monitor_config\\',
21:42:22 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
21:42:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
21:42:22 INFO - 'MOZ_NO_REMOTE': '1',
21:42:22 INFO - 'NO_EM_RESTART': '1',
21:42:22 INFO - 'NUMBER_OF_PROCESSORS': '8',
21:42:22 INFO - 'OS': 'Windows_NT',
21:42:22 INFO - 'OURDRIVE': 'C:',
21:42:22 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;',
21:42:22 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
21:42:22 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
21:42:22 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
21:42:22 INFO - 'PROCESSOR_LEVEL': '6',
21:42:22 INFO - 'PROCESSOR_REVISION': '1e05',
21:42:22 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
21:42:22 INFO - 'PROGRAMFILES': 'C:\\Program Files',
21:42:22 INFO - 'PROMPT': '$P$G',
21:42:22 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
21:42:22 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
21:42:22 INFO - 'PUBLIC': 'C:\\Users\\Public',
21:42:22 INFO - 'PWD': 'C:\\slave\\test',
21:42:22 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
21:42:22 INFO - 'SLAVEDIR': 'C:\\slave\\',
21:42:22 INFO - 'SYSTEMDRIVE': 'C:',
21:42:22 INFO - 'SYSTEMROOT': 'C:\\windows',
21:42:22 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:42:22 INFO - 'TEST1': 'testie',
21:42:22 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:42:22 INFO - 'USERDOMAIN': 'T-W732-IX-039',
21:42:22 INFO - 'USERNAME': 'cltbld',
21:42:22 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
21:42:22 INFO - 'WINDIR': 'C:\\windows',
21:42:22 INFO - 'WINDOWS_TRACING_FLAGS': '3',
21:42:22 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
21:42:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
21:42:23 INFO - Ignoring indexes: https://pypi.python.org/simple/
21:42:23 INFO - Downloading/unpacking psutil>=3.1.1
21:42:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
21:42:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
21:42:24 INFO - Installing collected packages: psutil
21:42:25 INFO - Successfully installed psutil
21:42:25 INFO - Cleaning up...
21:42:25 INFO - Return code: 0
21:42:25 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv
21:42:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
21:42:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
21:42:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
21:42:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
21:42:25 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018A1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01945E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0184B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01952E90>, '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 0x0136A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A14520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-039', 'USERDOMAIN': 'T-W732-IX-039', '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-039', '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
21:42:25 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in C:\slave\test\build
21:42:25 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3
21:42:25 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
21:42:25 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
21:42:25 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
21:42:25 INFO - 'COMPUTERNAME': 'T-W732-IX-039',
21:42:25 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
21:42:25 INFO - 'CYGWINBASE': 'C:\\cygwin',
21:42:25 INFO - 'DCLOCATION': 'SCL3',
21:42:25 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
21:42:25 INFO - 'FP_NO_HOST_CHECK': 'NO',
21:42:25 INFO - 'HOMEDRIVE': 'C:',
21:42:25 INFO - 'HOMEPATH': '\\Users\\cltbld',
21:42:25 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
21:42:25 INFO - 'KTS_VERSION': '1.19c',
21:42:25 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
21:42:25 INFO - 'LOGONSERVER': '\\\\T-W732-IX-039',
21:42:25 INFO - 'MONDIR': 'C:\\Monitor_config\\',
21:42:25 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
21:42:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
21:42:25 INFO - 'MOZ_NO_REMOTE': '1',
21:42:25 INFO - 'NO_EM_RESTART': '1',
21:42:25 INFO - 'NUMBER_OF_PROCESSORS': '8',
21:42:25 INFO - 'OS': 'Windows_NT',
21:42:25 INFO - 'OURDRIVE': 'C:',
21:42:25 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
21:42:25 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
21:42:25 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
21:42:25 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
21:42:25 INFO - 'PROCESSOR_LEVEL': '6',
21:42:25 INFO - 'PROCESSOR_REVISION': '1e05',
21:42:25 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
21:42:25 INFO - 'PROGRAMFILES': 'C:\\Program Files',
21:42:25 INFO - 'PROMPT': '$P$G',
21:42:25 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
21:42:25 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
21:42:25 INFO - 'PUBLIC': 'C:\\Users\\Public',
21:42:25 INFO - 'PWD': 'C:\\slave\\test',
21:42:25 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
21:42:25 INFO - 'SLAVEDIR': 'C:\\slave\\',
21:42:25 INFO - 'SYSTEMDRIVE': 'C:',
21:42:25 INFO - 'SYSTEMROOT': 'C:\\windows',
21:42:25 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:42:25 INFO - 'TEST1': 'testie',
21:42:25 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:42:25 INFO - 'USERDOMAIN': 'T-W732-IX-039',
21:42:25 INFO - 'USERNAME': 'cltbld',
21:42:25 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
21:42:25 INFO - 'WINDIR': 'C:\\windows',
21:42:25 INFO - 'WINDOWS_TRACING_FLAGS': '3',
21:42:25 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
21:42:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
21:42:25 INFO - Ignoring indexes: https://pypi.python.org/simple/
21:42:25 INFO - Downloading/unpacking mozsystemmonitor==0.3
21:42:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
21:42:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
21:42:26 INFO - Downloading mozsystemmonitor-0.3.tar.gz
21:42:26 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor
21:42:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in c:\slave\test\build\venv\lib\site-packages (from mozsystemmonitor==0.3)
21:42:26 INFO - Installing collected packages: mozsystemmonitor
21:42:26 INFO - Running setup.py install for mozsystemmonitor
21:42:26 INFO - Successfully installed mozsystemmonitor
21:42:26 INFO - Cleaning up...
21:42:26 INFO - Return code: 0
21:42:27 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv
21:42:27 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')]}
21:42:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
21:42:27 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')]}
21:42:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
21:42:27 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 0x018A1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01945E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0184B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01952E90>, '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 0x0136A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A14520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-039', 'USERDOMAIN': 'T-W732-IX-039', '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-039', '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
21:42:27 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in C:\slave\test\build
21:42:27 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1
21:42:27 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
21:42:27 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
21:42:27 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
21:42:27 INFO - 'COMPUTERNAME': 'T-W732-IX-039',
21:42:27 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
21:42:27 INFO - 'CYGWINBASE': 'C:\\cygwin',
21:42:27 INFO - 'DCLOCATION': 'SCL3',
21:42:27 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
21:42:27 INFO - 'FP_NO_HOST_CHECK': 'NO',
21:42:27 INFO - 'HOMEDRIVE': 'C:',
21:42:27 INFO - 'HOMEPATH': '\\Users\\cltbld',
21:42:27 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
21:42:27 INFO - 'KTS_VERSION': '1.19c',
21:42:27 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
21:42:27 INFO - 'LOGONSERVER': '\\\\T-W732-IX-039',
21:42:27 INFO - 'MONDIR': 'C:\\Monitor_config\\',
21:42:27 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
21:42:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
21:42:27 INFO - 'MOZ_NO_REMOTE': '1',
21:42:27 INFO - 'NO_EM_RESTART': '1',
21:42:27 INFO - 'NUMBER_OF_PROCESSORS': '8',
21:42:27 INFO - 'OS': 'Windows_NT',
21:42:27 INFO - 'OURDRIVE': 'C:',
21:42:27 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
21:42:27 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
21:42:27 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
21:42:27 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
21:42:27 INFO - 'PROCESSOR_LEVEL': '6',
21:42:27 INFO - 'PROCESSOR_REVISION': '1e05',
21:42:27 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
21:42:27 INFO - 'PROGRAMFILES': 'C:\\Program Files',
21:42:27 INFO - 'PROMPT': '$P$G',
21:42:27 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
21:42:27 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
21:42:27 INFO - 'PUBLIC': 'C:\\Users\\Public',
21:42:27 INFO - 'PWD': 'C:\\slave\\test',
21:42:27 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
21:42:27 INFO - 'SLAVEDIR': 'C:\\slave\\',
21:42:27 INFO - 'SYSTEMDRIVE': 'C:',
21:42:27 INFO - 'SYSTEMROOT': 'C:\\windows',
21:42:27 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:42:27 INFO - 'TEST1': 'testie',
21:42:27 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:42:27 INFO - 'USERDOMAIN': 'T-W732-IX-039',
21:42:27 INFO - 'USERNAME': 'cltbld',
21:42:27 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
21:42:27 INFO - 'WINDIR': 'C:\\windows',
21:42:27 INFO - 'WINDOWS_TRACING_FLAGS': '3',
21:42:27 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
21:42:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
21:42:27 INFO - Ignoring indexes: https://pypi.python.org/simple/
21:42:27 INFO - Downloading/unpacking jsonschema==2.5.1
21:42:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
21:42:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
21:42:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl
21:42:28 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1)
21:42:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
21:42:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
21:42:28 INFO - Downloading functools32-3.2.3-2.tar.gz
21:42:28 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32
21:42:28 INFO - warning: no files found matching '*.txt'
21:42:28 INFO - no previously-included directories found matching 'build'
21:42:28 INFO - no previously-included directories found matching 'dist'
21:42:28 INFO - no previously-included directories found matching '.git*'
21:42:28 INFO - Installing collected packages: jsonschema, functools32
21:42:28 INFO - Running setup.py install for functools32
21:42:28 INFO - warning: no files found matching '*.txt'
21:42:28 INFO - no previously-included directories found matching 'build'
21:42:28 INFO - no previously-included directories found matching 'dist'
21:42:28 INFO - no previously-included directories found matching '.git*'
21:42:28 INFO - Successfully installed jsonschema functools32
21:42:28 INFO - Cleaning up...
21:42:29 INFO - Return code: 0
21:42:29 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv
21:42:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
21:42:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
21:42:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
21:42:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
21:42:29 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018A1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01945E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0184B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01952E90>, '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 0x0136A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A14520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-039', 'USERDOMAIN': 'T-W732-IX-039', '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-039', '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
21:42:29 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in C:\slave\test\build
21:42:29 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2
21:42:29 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
21:42:29 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
21:42:29 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
21:42:29 INFO - 'COMPUTERNAME': 'T-W732-IX-039',
21:42:29 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
21:42:29 INFO - 'CYGWINBASE': 'C:\\cygwin',
21:42:29 INFO - 'DCLOCATION': 'SCL3',
21:42:29 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
21:42:29 INFO - 'FP_NO_HOST_CHECK': 'NO',
21:42:29 INFO - 'HOMEDRIVE': 'C:',
21:42:29 INFO - 'HOMEPATH': '\\Users\\cltbld',
21:42:29 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
21:42:29 INFO - 'KTS_VERSION': '1.19c',
21:42:29 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
21:42:29 INFO - 'LOGONSERVER': '\\\\T-W732-IX-039',
21:42:29 INFO - 'MONDIR': 'C:\\Monitor_config\\',
21:42:29 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
21:42:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
21:42:29 INFO - 'MOZ_NO_REMOTE': '1',
21:42:29 INFO - 'NO_EM_RESTART': '1',
21:42:29 INFO - 'NUMBER_OF_PROCESSORS': '8',
21:42:29 INFO - 'OS': 'Windows_NT',
21:42:29 INFO - 'OURDRIVE': 'C:',
21:42:29 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
21:42:29 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
21:42:29 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
21:42:29 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
21:42:29 INFO - 'PROCESSOR_LEVEL': '6',
21:42:29 INFO - 'PROCESSOR_REVISION': '1e05',
21:42:29 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
21:42:29 INFO - 'PROGRAMFILES': 'C:\\Program Files',
21:42:29 INFO - 'PROMPT': '$P$G',
21:42:29 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
21:42:29 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
21:42:29 INFO - 'PUBLIC': 'C:\\Users\\Public',
21:42:29 INFO - 'PWD': 'C:\\slave\\test',
21:42:29 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
21:42:29 INFO - 'SLAVEDIR': 'C:\\slave\\',
21:42:29 INFO - 'SYSTEMDRIVE': 'C:',
21:42:29 INFO - 'SYSTEMROOT': 'C:\\windows',
21:42:29 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:42:29 INFO - 'TEST1': 'testie',
21:42:29 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:42:29 INFO - 'USERDOMAIN': 'T-W732-IX-039',
21:42:29 INFO - 'USERNAME': 'cltbld',
21:42:29 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
21:42:29 INFO - 'WINDIR': 'C:\\windows',
21:42:29 INFO - 'WINDOWS_TRACING_FLAGS': '3',
21:42:29 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
21:42:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
21:42:29 INFO - Ignoring indexes: https://pypi.python.org/simple/
21:42:29 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages
21:42:29 INFO - Cleaning up...
21:42:29 INFO - Return code: 0
21:42:29 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv
21:42:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
21:42:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
21:42:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
21:42:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
21:42:29 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018A1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01945E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0184B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01952E90>, '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 0x0136A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A14520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-039', 'USERDOMAIN': 'T-W732-IX-039', '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-039', '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
21:42:29 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in C:\slave\test\build
21:42:29 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4
21:42:29 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
21:42:29 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
21:42:29 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
21:42:29 INFO - 'COMPUTERNAME': 'T-W732-IX-039',
21:42:29 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
21:42:29 INFO - 'CYGWINBASE': 'C:\\cygwin',
21:42:29 INFO - 'DCLOCATION': 'SCL3',
21:42:29 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
21:42:29 INFO - 'FP_NO_HOST_CHECK': 'NO',
21:42:29 INFO - 'HOMEDRIVE': 'C:',
21:42:29 INFO - 'HOMEPATH': '\\Users\\cltbld',
21:42:29 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
21:42:29 INFO - 'KTS_VERSION': '1.19c',
21:42:29 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
21:42:29 INFO - 'LOGONSERVER': '\\\\T-W732-IX-039',
21:42:29 INFO - 'MONDIR': 'C:\\Monitor_config\\',
21:42:29 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
21:42:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
21:42:29 INFO - 'MOZ_NO_REMOTE': '1',
21:42:29 INFO - 'NO_EM_RESTART': '1',
21:42:29 INFO - 'NUMBER_OF_PROCESSORS': '8',
21:42:29 INFO - 'OS': 'Windows_NT',
21:42:29 INFO - 'OURDRIVE': 'C:',
21:42:29 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
21:42:29 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
21:42:29 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
21:42:29 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
21:42:29 INFO - 'PROCESSOR_LEVEL': '6',
21:42:29 INFO - 'PROCESSOR_REVISION': '1e05',
21:42:29 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
21:42:29 INFO - 'PROGRAMFILES': 'C:\\Program Files',
21:42:29 INFO - 'PROMPT': '$P$G',
21:42:29 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
21:42:29 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
21:42:29 INFO - 'PUBLIC': 'C:\\Users\\Public',
21:42:29 INFO - 'PWD': 'C:\\slave\\test',
21:42:29 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
21:42:29 INFO - 'SLAVEDIR': 'C:\\slave\\',
21:42:29 INFO - 'SYSTEMDRIVE': 'C:',
21:42:29 INFO - 'SYSTEMROOT': 'C:\\windows',
21:42:29 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:42:29 INFO - 'TEST1': 'testie',
21:42:29 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:42:29 INFO - 'USERDOMAIN': 'T-W732-IX-039',
21:42:29 INFO - 'USERNAME': 'cltbld',
21:42:29 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
21:42:29 INFO - 'WINDIR': 'C:\\windows',
21:42:29 INFO - 'WINDOWS_TRACING_FLAGS': '3',
21:42:29 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
21:42:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
21:42:30 INFO - Ignoring indexes: https://pypi.python.org/simple/
21:42:30 INFO - Downloading/unpacking blobuploader==1.2.4
21:42:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
21:42:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
21:42:31 INFO - Downloading blobuploader-1.2.4.tar.gz
21:42:31 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader
21:42:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4)
21:42:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
21:42:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
21:42:31 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests
21:42:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4)
21:42:32 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
21:42:32 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
21:42:32 INFO - Downloading docopt-0.6.1.tar.gz
21:42:32 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt
21:42:32 INFO - Installing collected packages: blobuploader, requests, docopt
21:42:32 INFO - Running setup.py install for blobuploader
21:42:32 INFO - Running setup.py install for requests
21:42:33 INFO - Running setup.py install for docopt
21:42:33 INFO - Successfully installed blobuploader requests docopt
21:42:33 INFO - Cleaning up...
21:42:34 INFO - Return code: 0
21:42:34 INFO - Installing None into virtualenv C:\slave\test\build/venv
21:42:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
21:42:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
21:42:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
21:42:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
21:42:34 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--no-deps', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018A1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01945E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0184B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01952E90>, '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 0x0136A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A14520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-039', 'USERDOMAIN': 'T-W732-IX-039', '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-039', '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
21:42:34 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--no-deps', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test\build\tests\config
21:42:34 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --no-deps --timeout 120 -r C:\slave\test\build\tests\config\mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub
21:42:34 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
21:42:34 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
21:42:34 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
21:42:34 INFO - 'COMPUTERNAME': 'T-W732-IX-039',
21:42:34 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
21:42:34 INFO - 'CYGWINBASE': 'C:\\cygwin',
21:42:34 INFO - 'DCLOCATION': 'SCL3',
21:42:34 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
21:42:34 INFO - 'FP_NO_HOST_CHECK': 'NO',
21:42:34 INFO - 'HOMEDRIVE': 'C:',
21:42:34 INFO - 'HOMEPATH': '\\Users\\cltbld',
21:42:34 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
21:42:34 INFO - 'KTS_VERSION': '1.19c',
21:42:34 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
21:42:34 INFO - 'LOGONSERVER': '\\\\T-W732-IX-039',
21:42:34 INFO - 'MONDIR': 'C:\\Monitor_config\\',
21:42:34 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
21:42:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
21:42:34 INFO - 'MOZ_NO_REMOTE': '1',
21:42:34 INFO - 'NO_EM_RESTART': '1',
21:42:34 INFO - 'NUMBER_OF_PROCESSORS': '8',
21:42:34 INFO - 'OS': 'Windows_NT',
21:42:34 INFO - 'OURDRIVE': 'C:',
21:42:34 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
21:42:34 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
21:42:34 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
21:42:34 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
21:42:34 INFO - 'PROCESSOR_LEVEL': '6',
21:42:34 INFO - 'PROCESSOR_REVISION': '1e05',
21:42:34 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
21:42:34 INFO - 'PROGRAMFILES': 'C:\\Program Files',
21:42:34 INFO - 'PROMPT': '$P$G',
21:42:34 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
21:42:34 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
21:42:34 INFO - 'PUBLIC': 'C:\\Users\\Public',
21:42:34 INFO - 'PWD': 'C:\\slave\\test',
21:42:34 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
21:42:34 INFO - 'SLAVEDIR': 'C:\\slave\\',
21:42:34 INFO - 'SYSTEMDRIVE': 'C:',
21:42:34 INFO - 'SYSTEMROOT': 'C:\\windows',
21:42:34 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:42:34 INFO - 'TEST1': 'testie',
21:42:34 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:42:34 INFO - 'USERDOMAIN': 'T-W732-IX-039',
21:42:34 INFO - 'USERNAME': 'cltbld',
21:42:34 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
21:42:34 INFO - 'WINDIR': 'C:\\windows',
21:42:34 INFO - 'WINDOWS_TRACING_FLAGS': '3',
21:42:34 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
21:42:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
21:42:34 INFO - Ignoring indexes: https://pypi.python.org/simple/
21:42:34 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
21:42:34 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-n0xrst-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
21:42:35 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
21:42:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-st530s-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
21:42:35 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
21:42:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-u91jih-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
21:42:35 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
21:42:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-5jpaur-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
21:42:35 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
21:42:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-3ctdsu-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
21:42:35 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
21:42:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ood3wi-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
21:42:35 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
21:42:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hshpts-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
21:42:35 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
21:42:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qgsnle-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
21:42:35 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
21:42:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ekbmct-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
21:42:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
21:42:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ydprrn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
21:42:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
21:42:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-4yo2on-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
21:42:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
21:42:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-s4ec2y-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
21:42:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
21:42:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jr6mqc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
21:42:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
21:42:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-tuyr7i-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
21:42:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
21:42:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-yz9lxa-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
21:42:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
21:42:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-yzwgjc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
21:42:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
21:42:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-d9fstp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
21:42:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
21:42:37 INFO - Running setup.py install for manifestparser
21:42:37 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts
21:42:37 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts
21:42:38 INFO - Running setup.py install for mozcrash
21:42:38 INFO - Running setup.py install for mozdebug
21:42:39 INFO - Running setup.py install for mozdevice
21:42:39 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts
21:42:39 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts
21:42:39 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts
21:42:39 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts
21:42:39 INFO - Running setup.py install for mozfile
21:42:40 INFO - Running setup.py install for mozhttpd
21:42:40 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts
21:42:40 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts
21:42:40 INFO - Running setup.py install for mozinfo
21:42:40 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts
21:42:40 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts
21:42:40 INFO - Running setup.py install for mozInstall
21:42:40 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts
21:42:40 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts
21:42:40 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts
21:42:40 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts
21:42:40 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts
21:42:40 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts
21:42:40 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts
21:42:40 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts
21:42:41 INFO - Running setup.py install for mozleak
21:42:41 INFO - Running setup.py install for mozlog
21:42:41 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts
21:42:41 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts
21:42:42 INFO - Running setup.py install for moznetwork
21:42:42 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts
21:42:42 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts
21:42:42 INFO - Running setup.py install for mozprocess
21:42:43 INFO - Running setup.py install for mozprofile
21:42:43 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts
21:42:43 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts
21:42:43 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts
21:42:43 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts
21:42:43 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts
21:42:43 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts
21:42:43 INFO - Running setup.py install for mozrunner
21:42:44 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts
21:42:44 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts
21:42:44 INFO - Running setup.py install for mozscreenshot
21:42:44 INFO - Running setup.py install for moztest
21:42:45 INFO - Running setup.py install for mozversion
21:42:45 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts
21:42:45 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts
21:42:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion
21:42:45 INFO - Cleaning up...
21:42:45 INFO - Return code: 0
21:42:45 INFO - Installing None into virtualenv C:\slave\test\build/venv
21:42:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
21:42:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
21:42:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
21:42:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
21:42:45 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018A1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01945E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0184B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01952E90>, '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 0x0136A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A14520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-039', 'USERDOMAIN': 'T-W732-IX-039', '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-039', '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
21:42:45 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test\build\tests\config
21:42:45 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 -r C:\slave\test\build\tests\config\mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub
21:42:45 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
21:42:45 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
21:42:45 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
21:42:45 INFO - 'COMPUTERNAME': 'T-W732-IX-039',
21:42:45 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
21:42:45 INFO - 'CYGWINBASE': 'C:\\cygwin',
21:42:45 INFO - 'DCLOCATION': 'SCL3',
21:42:45 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
21:42:45 INFO - 'FP_NO_HOST_CHECK': 'NO',
21:42:45 INFO - 'HOMEDRIVE': 'C:',
21:42:45 INFO - 'HOMEPATH': '\\Users\\cltbld',
21:42:45 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
21:42:45 INFO - 'KTS_VERSION': '1.19c',
21:42:45 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
21:42:45 INFO - 'LOGONSERVER': '\\\\T-W732-IX-039',
21:42:45 INFO - 'MONDIR': 'C:\\Monitor_config\\',
21:42:45 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
21:42:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
21:42:45 INFO - 'MOZ_NO_REMOTE': '1',
21:42:45 INFO - 'NO_EM_RESTART': '1',
21:42:45 INFO - 'NUMBER_OF_PROCESSORS': '8',
21:42:45 INFO - 'OS': 'Windows_NT',
21:42:45 INFO - 'OURDRIVE': 'C:',
21:42:45 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
21:42:45 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
21:42:45 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
21:42:45 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
21:42:45 INFO - 'PROCESSOR_LEVEL': '6',
21:42:45 INFO - 'PROCESSOR_REVISION': '1e05',
21:42:45 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
21:42:45 INFO - 'PROGRAMFILES': 'C:\\Program Files',
21:42:45 INFO - 'PROMPT': '$P$G',
21:42:45 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
21:42:45 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
21:42:45 INFO - 'PUBLIC': 'C:\\Users\\Public',
21:42:45 INFO - 'PWD': 'C:\\slave\\test',
21:42:45 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
21:42:45 INFO - 'SLAVEDIR': 'C:\\slave\\',
21:42:45 INFO - 'SYSTEMDRIVE': 'C:',
21:42:45 INFO - 'SYSTEMROOT': 'C:\\windows',
21:42:45 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:42:45 INFO - 'TEST1': 'testie',
21:42:45 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:42:45 INFO - 'USERDOMAIN': 'T-W732-IX-039',
21:42:45 INFO - 'USERNAME': 'cltbld',
21:42:45 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
21:42:45 INFO - 'WINDIR': 'C:\\windows',
21:42:45 INFO - 'WINDOWS_TRACING_FLAGS': '3',
21:42:45 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
21:42:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
21:42:46 INFO - Ignoring indexes: https://pypi.python.org/simple/
21:42:46 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
21:42:46 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-0ombov-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
21:42:46 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///C:/slave/test/build/tests/mozbase/manifestparser in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 1))
21:42:46 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
21:42:46 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pz1ddt-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
21:42:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///C:/slave/test/build/tests/mozbase/mozcrash in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2))
21:42:46 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
21:42:46 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pmtnwa-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
21:42:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///C:/slave/test/build/tests/mozbase/mozdebug in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 3))
21:42:46 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
21:42:46 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-uyfhjj-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
21:42:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///C:/slave/test/build/tests/mozbase/mozdevice in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4))
21:42:46 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
21:42:46 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kurfdc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
21:42:47 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))
21:42:47 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
21:42:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wd_htq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
21:42:47 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))
21:42:47 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
21:42:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xnxvey-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
21:42:47 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))
21:42:47 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
21:42:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-slqnrx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
21:42:47 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))
21:42:47 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
21:42:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-1fenps-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
21:42:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///C:/slave/test/build/tests/mozbase/mozleak in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 9))
21:42:47 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
21:42:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bhgnqh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
21:42:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///C:/slave/test/build/tests/mozbase/mozlog in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10))
21:42:47 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
21:42:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-1z8hf4-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
21:42:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///C:/slave/test/build/tests/mozbase/moznetwork in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 11))
21:42:47 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
21:42:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fphidu-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
21:42:48 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))
21:42:48 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
21:42:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-nyw4ne-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
21:42:48 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))
21:42:48 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
21:42:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-nugf4a-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
21:42:48 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))
21:42:48 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
21:42:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zn3nok-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
21:42:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///C:/slave/test/build/tests/mozbase/mozscreenshot in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 15))
21:42:48 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
21:42:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-0uwd_a-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
21:42:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///C:/slave/test/build/tests/mozbase/moztest in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 16))
21:42:48 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
21:42:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-sftm0x-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
21:42:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///C:/slave/test/build/tests/mozbase/mozversion in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 17))
21:42:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==0.17->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2))
21:42:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==0.17->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2))
21:42:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in c:\slave\test\build\venv\lib\site-packages (from mozdebug==0.1->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 3))
21:42:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.48->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4))
21:42:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.48->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4))
21:42:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10))
21:42: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
21:42: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
21:42:49 INFO - Downloading blessings-1.6.tar.gz
21:42:49 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings
21:42:50 INFO - Installing collected packages: blessings
21:42:50 INFO - Running setup.py install for blessings
21:42:50 INFO - Successfully installed blessings
21:42:50 INFO - Cleaning up...
21:42:53 INFO - Return code: 0
21:42:53 INFO - Done creating virtualenv C:\slave\test\build/venv.
21:42:53 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
21:42:53 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
21:42:53 INFO - Reading from file tmpfile_stdout
21:42:53 INFO - Using _rmtree_windows ...
21:42:53 INFO - Using _rmtree_windows ...
21:42:53 INFO - Current package versions:
21:42:53 INFO - blessings == 1.6
21:42:53 INFO - blobuploader == 1.2.4
21:42:53 INFO - docopt == 0.6.1
21:42:53 INFO - functools32 == 3.2.3-2
21:42:53 INFO - jsonschema == 2.5.1
21:42:53 INFO - manifestparser == 1.1
21:42:53 INFO - mozInstall == 1.12
21:42:53 INFO - mozcrash == 0.17
21:42:53 INFO - mozdebug == 0.1
21:42:53 INFO - mozdevice == 0.48
21:42:53 INFO - mozfile == 1.2
21:42:53 INFO - mozhttpd == 0.7
21:42:53 INFO - mozinfo == 0.9
21:42:53 INFO - mozleak == 0.1
21:42:53 INFO - mozlog == 3.4
21:42:53 INFO - moznetwork == 0.27
21:42:53 INFO - mozprocess == 0.23
21:42:53 INFO - mozprofile == 0.28
21:42:53 INFO - mozrunner == 6.12
21:42:53 INFO - mozscreenshot == 0.1
21:42:53 INFO - mozsystemmonitor == 0.3
21:42:53 INFO - moztest == 0.7
21:42:53 INFO - mozversion == 1.4
21:42:53 INFO - psutil == 3.1.1
21:42:53 INFO - requests == 1.2.3
21:42:53 INFO - Installing None into virtualenv C:\slave\test\build/venv
21:42: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')]}
21:42:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
21:42: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')]}
21:42:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
21:42:53 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\talos\\requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018A1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01945E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0184B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01952E90>, '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 0x0136A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A14520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-039', 'USERDOMAIN': 'T-W732-IX-039', '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-039', '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
21:42:53 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\talos\\requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test\build\tests\talos
21:42:53 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 -r C:\slave\test\build\tests\talos\requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub
21:42:53 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
21:42:53 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
21:42:53 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
21:42:53 INFO - 'COMPUTERNAME': 'T-W732-IX-039',
21:42:53 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
21:42:53 INFO - 'CYGWINBASE': 'C:\\cygwin',
21:42:53 INFO - 'DCLOCATION': 'SCL3',
21:42:53 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
21:42:53 INFO - 'FP_NO_HOST_CHECK': 'NO',
21:42:53 INFO - 'HOMEDRIVE': 'C:',
21:42:53 INFO - 'HOMEPATH': '\\Users\\cltbld',
21:42:53 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
21:42:53 INFO - 'KTS_VERSION': '1.19c',
21:42:53 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
21:42:53 INFO - 'LOGONSERVER': '\\\\T-W732-IX-039',
21:42:53 INFO - 'MONDIR': 'C:\\Monitor_config\\',
21:42:53 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
21:42:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
21:42:53 INFO - 'MOZ_NO_REMOTE': '1',
21:42:53 INFO - 'NO_EM_RESTART': '1',
21:42:53 INFO - 'NUMBER_OF_PROCESSORS': '8',
21:42:53 INFO - 'OS': 'Windows_NT',
21:42:53 INFO - 'OURDRIVE': 'C:',
21:42: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;',
21:42:53 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
21:42:53 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
21:42:53 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
21:42:53 INFO - 'PROCESSOR_LEVEL': '6',
21:42:53 INFO - 'PROCESSOR_REVISION': '1e05',
21:42:53 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
21:42:53 INFO - 'PROGRAMFILES': 'C:\\Program Files',
21:42:53 INFO - 'PROMPT': '$P$G',
21:42:53 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
21:42:53 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
21:42:53 INFO - 'PUBLIC': 'C:\\Users\\Public',
21:42:53 INFO - 'PWD': 'C:\\slave\\test',
21:42:53 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
21:42:53 INFO - 'SLAVEDIR': 'C:\\slave\\',
21:42:53 INFO - 'SYSTEMDRIVE': 'C:',
21:42:53 INFO - 'SYSTEMROOT': 'C:\\windows',
21:42:53 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:42:53 INFO - 'TEST1': 'testie',
21:42:53 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:42:53 INFO - 'USERDOMAIN': 'T-W732-IX-039',
21:42:53 INFO - 'USERNAME': 'cltbld',
21:42:53 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
21:42:53 INFO - 'WINDIR': 'C:\\windows',
21:42:53 INFO - 'WINDOWS_TRACING_FLAGS': '3',
21:42:53 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
21:42:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
21:42:53 INFO - Ignoring indexes: https://pypi.python.org/simple/
21:42:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 1))
21:42:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 2))
21:42:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 3))
21:42:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 4))
21:42:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 5))
21:42:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 6))
21:42:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 7))
21:42:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 8))
21:42:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 9))
21:42:53 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in c:\slave\test\build\venv\lib\site-packages (from mozlog>=3.1->-r C:\slave\test\build\tests\talos\requirements.txt (line 1))
21:42:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test\build\venv\lib\site-packages (from mozhttpd>=0.7->-r C:\slave\test\build\tests\talos\requirements.txt (line 4))
21:42:53 INFO - Cleaning up...
21:42:53 INFO - Return code: 0
21:42:53 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv
21:42: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')]}
21:42:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
21:42: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')]}
21:42:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
21:42:53 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018A1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01945E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0184B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01952E90>, '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 0x0136A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A14520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-039', 'USERDOMAIN': 'T-W732-IX-039', '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-039', '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
21:42:53 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in C:\slave\test\build
21:42:53 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema
21:42:53 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
21:42:53 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
21:42:53 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
21:42:53 INFO - 'COMPUTERNAME': 'T-W732-IX-039',
21:42:53 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
21:42:53 INFO - 'CYGWINBASE': 'C:\\cygwin',
21:42:53 INFO - 'DCLOCATION': 'SCL3',
21:42:53 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
21:42:53 INFO - 'FP_NO_HOST_CHECK': 'NO',
21:42:53 INFO - 'HOMEDRIVE': 'C:',
21:42:53 INFO - 'HOMEPATH': '\\Users\\cltbld',
21:42:53 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
21:42:53 INFO - 'KTS_VERSION': '1.19c',
21:42:53 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
21:42:53 INFO - 'LOGONSERVER': '\\\\T-W732-IX-039',
21:42:53 INFO - 'MONDIR': 'C:\\Monitor_config\\',
21:42:53 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
21:42:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
21:42:53 INFO - 'MOZ_NO_REMOTE': '1',
21:42:53 INFO - 'NO_EM_RESTART': '1',
21:42:53 INFO - 'NUMBER_OF_PROCESSORS': '8',
21:42:53 INFO - 'OS': 'Windows_NT',
21:42:53 INFO - 'OURDRIVE': 'C:',
21:42: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;',
21:42:53 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
21:42:53 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
21:42:53 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
21:42:53 INFO - 'PROCESSOR_LEVEL': '6',
21:42:53 INFO - 'PROCESSOR_REVISION': '1e05',
21:42:53 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
21:42:53 INFO - 'PROGRAMFILES': 'C:\\Program Files',
21:42:53 INFO - 'PROMPT': '$P$G',
21:42:53 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
21:42:53 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
21:42:53 INFO - 'PUBLIC': 'C:\\Users\\Public',
21:42:53 INFO - 'PWD': 'C:\\slave\\test',
21:42:53 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
21:42:53 INFO - 'SLAVEDIR': 'C:\\slave\\',
21:42:53 INFO - 'SYSTEMDRIVE': 'C:',
21:42:53 INFO - 'SYSTEMROOT': 'C:\\windows',
21:42:53 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:42:53 INFO - 'TEST1': 'testie',
21:42:53 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:42:53 INFO - 'USERDOMAIN': 'T-W732-IX-039',
21:42:53 INFO - 'USERNAME': 'cltbld',
21:42:53 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
21:42:53 INFO - 'WINDIR': 'C:\\windows',
21:42:53 INFO - 'WINDOWS_TRACING_FLAGS': '3',
21:42:53 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
21:42:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
21:42:54 INFO - Ignoring indexes: https://pypi.python.org/simple/
21:42:54 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages
21:42:54 INFO - Cleaning up...
21:42:54 INFO - Return code: 0
21:42:54 INFO - Running post-action listener: _resource_record_post_action
21:42:54 INFO - Running post-action listener: _start_resource_monitoring
21:42:54 INFO - Starting resource monitoring.
21:42:54 INFO - [mozharness: 2016-09-26 04:42:54.457000Z] Finished create-virtualenv step (success)
21:42:54 INFO - [mozharness: 2016-09-26 04:42:54.458000Z] Running install step.
21:42:54 INFO - Running pre-action listener: _resource_record_pre_action
21:42:54 INFO - Running main action method: install
21:42:54 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
21:42:54 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
21:42:54 INFO - Reading from file tmpfile_stdout
21:42:54 INFO - Using _rmtree_windows ...
21:42:54 INFO - Using _rmtree_windows ...
21:42:54 INFO - Detecting whether we're running mozinstall >=1.0...
21:42:54 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h']
21:42:54 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h
21:42:54 INFO - Reading from file tmpfile_stdout
21:42:54 INFO - Output received:
21:42:54 INFO - Usage: mozinstall-script.py [options] installer
21:42:54 INFO - Options:
21:42:54 INFO - -h, --help show this help message and exit
21:42:54 INFO - -d DEST, --destination=DEST
21:42:54 INFO - Directory to install application into. [default:
21:42:54 INFO - "C:\slave\test"]
21:42:54 INFO - --app=APP Application being installed. [default: firefox]
21:42:54 INFO - Using _rmtree_windows ...
21:42:54 INFO - Using _rmtree_windows ...
21:42:54 INFO - mkdir: C:\slave\test\build\application
21:42:54 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.zip', '--destination', 'C:\\slave\\test\\build\\application']
21:42:54 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py C:\slave\test\build\firefox-52.0a1.en-US.win32.zip --destination C:\slave\test\build\application
21:42:57 INFO - Reading from file tmpfile_stdout
21:42:57 INFO - Output received:
21:42:57 INFO - C:\slave\test\build\application\firefox\firefox.exe
21:42:57 INFO - Using _rmtree_windows ...
21:42:57 INFO - Using _rmtree_windows ...
21:42:57 INFO - Running post-action listener: _resource_record_post_action
21:42:57 INFO - [mozharness: 2016-09-26 04:42:57.263000Z] Finished install step (success)
21:42:57 INFO - [mozharness: 2016-09-26 04:42:57.263000Z] Running run-tests step.
21:42:57 INFO - Running pre-action listener: _resource_record_pre_action
21:42:57 INFO - Running main action method: run_tests
21:42:57 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version']
21:42:57 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version
21:42:57 INFO - Python 2.7.3
21:42:57 INFO - Return code: 0
21:42:57 INFO - grabbing minidump binary from tooltool
21:42:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
21:42:57 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 0x01952E90>, '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 0x0136A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A14520>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1
21:42:57 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
21:42:57 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
21:42:57 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
21:42:57 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'...
21:42:57 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmpl6jccq
21:42:57 INFO - INFO - File integrity verified, renaming tmpl6jccq to win32-minidump_stackwalk.exe
21:42:57 INFO - Return code: 0
21:42:57 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755
21:42:57 INFO - mkdir: C:\slave\test\build\blobber_upload_dir
21:42:57 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir
21:42:57 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe
21:42:57 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir
21:42:57 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/J4XRUAtCR3qHKk2_hM0uVA/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-039', '--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
21:42:57 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --branchName Autoland --suite dromaeojs --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/J4XRUAtCR3qHKk2_hM0uVA/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-039 --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
21:42:57 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
21:42:57 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
21:42:57 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
21:42:57 INFO - 'COMPUTERNAME': 'T-W732-IX-039',
21:42:57 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
21:42:57 INFO - 'CYGWINBASE': 'C:\\cygwin',
21:42:57 INFO - 'DCLOCATION': 'SCL3',
21:42:57 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
21:42:57 INFO - 'FP_NO_HOST_CHECK': 'NO',
21:42:57 INFO - 'HOMEDRIVE': 'C:',
21:42:57 INFO - 'HOMEPATH': '\\Users\\cltbld',
21:42:57 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
21:42:57 INFO - 'KTS_VERSION': '1.19c',
21:42:57 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
21:42:57 INFO - 'LOGONSERVER': '\\\\T-W732-IX-039',
21:42:57 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir',
21:42:57 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe',
21:42:57 INFO - 'MONDIR': 'C:\\Monitor_config\\',
21:42:57 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
21:42:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
21:42:57 INFO - 'MOZ_NO_REMOTE': '1',
21:42:57 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir',
21:42:57 INFO - 'NO_EM_RESTART': '1',
21:42:57 INFO - 'NUMBER_OF_PROCESSORS': '8',
21:42:57 INFO - 'OS': 'Windows_NT',
21:42:57 INFO - 'OURDRIVE': 'C:',
21:42:57 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
21:42:57 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
21:42:57 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
21:42:57 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
21:42:57 INFO - 'PROCESSOR_LEVEL': '6',
21:42:57 INFO - 'PROCESSOR_REVISION': '1e05',
21:42:57 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
21:42:57 INFO - 'PROGRAMFILES': 'C:\\Program Files',
21:42:57 INFO - 'PROMPT': '$P$G',
21:42:57 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
21:42:57 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
21:42:57 INFO - 'PUBLIC': 'C:\\Users\\Public',
21:42:57 INFO - 'PWD': 'C:\\slave\\test',
21:42:57 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos',
21:42:57 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
21:42:57 INFO - 'SLAVEDIR': 'C:\\slave\\',
21:42:57 INFO - 'SYSTEMDRIVE': 'C:',
21:42:57 INFO - 'SYSTEMROOT': 'C:\\windows',
21:42:57 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:42:57 INFO - 'TEST1': 'testie',
21:42:57 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:42:57 INFO - 'USERDOMAIN': 'T-W732-IX-039',
21:42:57 INFO - 'USERNAME': 'cltbld',
21:42:57 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
21:42:57 INFO - 'WINDIR': 'C:\\windows',
21:42:57 INFO - 'WINDOWS_TRACING_FLAGS': '3',
21:42:57 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
21:42:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
21:42:57 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/J4XRUAtCR3qHKk2_hM0uVA/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-039', '--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
21:42:59 INFO - mozversion application_buildid: 20160925183005
21:42:59 INFO - mozversion application_changeset: 41e054b0c9635c17ee91374e0d774ae3daf492b7
21:42:59 INFO - mozversion application_display_name: Nightly
21:42:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
21:42:59 INFO - mozversion application_name: Firefox
21:42:59 INFO - mozversion application_remotingname: firefox
21:42:59 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland
21:42:59 INFO - mozversion application_vendor: Mozilla
21:42:59 INFO - mozversion application_version: 52.0a1
21:42:59 INFO - mozversion platform_buildid: 20160925183005
21:42:59 INFO - mozversion platform_changeset: 41e054b0c9635c17ee91374e0d774ae3daf492b7
21:42:59 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland
21:42:59 INFO - mozversion platform_version: 52.0a1
21:42:59 INFO - using testdate: 1474864979
21:42:59 INFO - actual date: 1474864979
21:42:59 INFO - starting webserver on 'localhost:49897'
21:42:59 INFO - SUITE-START | Running 2 tests
21:42:59 INFO - TEST-START | dromaeo_css
21:42:59 INFO - operating with platform_type : w7_
21:42:59 INFO - Initialising browser for dromaeo_css test...
21:42:59 INFO - TEST-INFO | started process 4000 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpmhryac\profile http://localhost:49897/getInfo.html)
21:43:06 INFO - PROCESS | 4000 | __metrics Screen width/height:1600/1200
21:43:06 INFO - PROCESS | 4000 | colorDepth:24
21:43:06 INFO - PROCESS | 4000 | Browser inner width/height: 1010/674
21:43:06 INFO - PROCESS | 4000 | __metrics
21:43:06 INFO - PROCESS | 4000 | 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"
21:43:06 INFO - PROCESS | 4000 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
21:43:07 INFO - TEST-INFO | 4000: exit 0
21:43:07 INFO - Browser initialized.
21:43:07 INFO - Running cycle 1/1 for dromaeo_css test...
21:43:07 INFO - TEST-INFO | started process 3540 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpmhryac\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1)
21:43:08 INFO - PROCESS | 3540 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0
21:43:19 INFO - PROCESS | 3540 | RSS: Main: 124182528
21:43:19 INFO - PROCESS | 3540 |
21:44:10 INFO - PROCESS | 3540 | 1474865050326 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]
21:46:20 INFO - PROCESS | 3540 | Cycle 1(1): loaded http://localhost:49897/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49897/tests/dromaeo/cssquery-ext.html)
21:46:21 INFO - PROCESS | 3540 | RSS: Main: 206196736
21:46:21 INFO - PROCESS | 3540 |
21:49:23 INFO - PROCESS | 3540 | Cycle 1(1): loaded http://localhost:49897/tests/dromaeo/cssquery-ext.html (next: http://localhost:49897/tests/dromaeo/cssquery-jquery.html)
21:49:24 INFO - PROCESS | 3540 | RSS: Main: 198090752
21:49:24 INFO - PROCESS | 3540 |
21:52:26 INFO - PROCESS | 3540 | Cycle 1(1): loaded http://localhost:49897/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49897/tests/dromaeo/cssquery-mootools.html)
21:52:27 INFO - PROCESS | 3540 | RSS: Main: 152993792
21:52:27 INFO - PROCESS | 3540 |
21:55:29 INFO - PROCESS | 3540 | Cycle 1(1): loaded http://localhost:49897/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49897/tests/dromaeo/cssquery-prototype.html)
21:55:29 INFO - PROCESS | 3540 | RSS: Main: 148430848
21:55:29 INFO - PROCESS | 3540 |
21:58:32 INFO - PROCESS | 3540 | Cycle 1(1): loaded http://localhost:49897/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49897/tests/dromaeo/cssquery-yui.html)
21:58:33 INFO - PROCESS | 3540 | RSS: Main: 132820992
21:58:33 INFO - PROCESS | 3540 |
22:01:35 INFO - PROCESS | 3540 | Cycle 1(1): loaded http://localhost:49897/tests/dromaeo/cssquery-yui.html (next: http://localhost:49897/tests/dromaeo/cssquery-dojo.html)
22:01:36 INFO - PROCESS | 3540 | RSS: Main: 322105344
22:01:36 INFO - PROCESS | 3540 |
22:01:36 INFO - PROCESS | 3540 | __start_tp_report
22:01:36 INFO - PROCESS | 3540 | _x_x_mozilla_page_load
22:01:36 INFO - PROCESS | 3540 | _x_x_mozilla_page_load_details
22:01:36 INFO - PROCESS | 3540 | |i|pagename|runs|
22:01:36 INFO - PROCESS | 3540 | |0;dojo.html;2691.6904555976043;2729.6178535005097;2769.556870900655;2780;2780.4717103750286;4719.056188762248;4748.240281554952;4774.761261936898;4774.832880849171;4850.442199147099;4556;4669.626429885601;4702.9529704702945;4727.716337019781;4757.714537127766;4050.230456213317;4142.502899652046;4180.519240287375;4187.392828039932;4212.431321771553;4141.896452588694;4164.500259968793;4191.832326706928;4208.621224089831;4223.662107031446;4080.510338759353;4179.623833854953;4253.723507971988;4260.893477663068;4261.4460120184485;3827.2345530893654;4083.7549747015273;4111.93089796652;4226.323788193874;4232.851850185229;3835.347990841564;4078;4080.326746086892;4104.240715467648;4168.8332466701295;4560.384348113029;4561;4655.790489427984;4674.929876051862;4681.133990211788;4793.01743142655;4824.252240902666;4856.125897338477;4944.579710724593;4962.826301079445;4871.683340582851;4932.432770231398;4975.104481193383;4990.87522811929;5028.1200789862;4480.081583275421;4576.244919588264;4645.605123564501;4670.159371313162;4674.415698037746;5014.899702006012;5021.397432308147;5119.948800512022;5129.743512824344;5141.126008578551;4793.496682848251;4828.565429111327;4832.057748739033;4839.588634966068;4897.583705385011;4905.5094490550655;4923.950760492421;4956.3804524434445;4957.553820156203;4961.231009193581;4934.432540257916;4939.481354457802;4941.110600092018;4948.331975183376;4952.257161425815;4907.386576677915;4948.331975183376;4952.083864485082;4953.8018479261145;4958.157113290749;3306.5866766654167;3312.6190488094176;3336.616289126733;3343;3359.0258824940984;2969.703029697013;3041.057272245611;3077.1999280186865;3078.338157296192;3080.106769036999;4690.249560070372;4696.671233013656;4780.713157210578;4786.258129989787;4787.521247875255;4903.9946810903675;4954.8761280968265;4963.181075122637;4999.400071991385;5017.54842064216;4738.170820106537;4774.498677638868;4785.736784476818;4787.904241915142;4788.090262850047;4105.692073094529;4142.896427589274;4168.124693814333;4171.561985991488;4204.642605378577;5204.76578553966;5286.735663216824;5301.867453313621;5368.275282836845;5413.783448662089;4978.079055374767;4979.278004689228;5048.141815891381;5087.414947280962;5100.438951715382;5192.766325515437;5202.323697919246;5219.2953951216095;5237.8428647140645;5268.868278293074;4861.562459378673;4866.829660961849;4959.851204463872;4964.503549645006;4970.378702662168;4949.851504454872;4962.206047032458;5050.924236136388;5067.594592432671;5068.721220332917;4824.782884770264;4846.39420072491;4849.078675051729;4878.56092951636;4919.1883339248625;4845.103655823684;4919.680220785637;4951.950480495293;4952.331435256195;4966.205407134986;4768.165571024987;4768.237082066853;4833.661643684908;4859.246816743411;4881.219004959189;4747.145513807548;4778.450478194913;4853.878653033702;4859.805607775649;4965.304857319906;4753.619710423227;4800.831970881002;4882.510834195462;4890.92663610039;4894.339264199288
22:01:36 INFO - PROCESS | 3540 | |1;ext.html;521.3769545393254;550.3945659774248;559.7928766356449;582.5427039773775;594.3521561497967;29007.274818129557;29887.551686724688;31102.84448577757;40337.59662403375;40639;40832.387514187234;41361.55234566791;41432.79283603578;41749.37375939363;41909.790451047775;7665.501742386755;8167.224113709218;8315.126911674277;8611.397202195818;8618.827623447527;6395.968020159893;6475.449586785129;6604.174478190226;6663.666816659172;9869.506524673774;9556.754162771744;9690.612375504972;9881.110700036996;10234.488275586227;10381.896181038206;9744.561494732754;10082.294239403282;10168.745781355452;10432.269741118125;10601.522931468102;6741.258461569223;6863.279355667633;9834.459104749236;10382.013708697661;10631.289021299519;38921.80539097286;39204;39312.21375572501;39381.40927886084;39392.80303598464;3087.3516561522106;3114.252579380955;3272.280098378377;3316.651751566099;3529.982350088259;31343.529847052312;31511.21221969469;31777;31893.521597176063;32267;3597.4963505109304;3688.422727046782;3690.944635830465;3718.8698395556025;3748.6813620842263;324338;332198;332444.0133398001;333620;334956.3252183748;52612.73693631507;53022;53236;53300;53361;54716.45283547193;54881.45118548843;55385;55470;55495.72252138794;13433.462661493431;13516.391762370717;13916.234607096505;13948.72102557943;13954.372053257628;10310.639127630497;10507.684769456928;10516.947415263028;10536.525856336484;10568.788624227473;13573.660658483419;13867.86132138686;15320.463783767515;15684.607884802972;15862.603434913988;6224.875502489925;6227.314995350508;6255.562110652301;6261.749530018748;6284.308726040132;818.5457071325352;919.5356345045843;925.0934084597133;979.9265055120894;1002.293383164867;28644.427111457655;28889.711102889123;28907.277318067216;29224;29644.703552964627;920.9861852072224;928.1229238369676;933.2021121940643;1061.8619579454776;1116.1405717597404;14049.22729249901;14504.047929872384;14619.833347495913;14683.41266349334;14947.850521494864;481709;482826;483296;486910;491704;47696.7615161922;48262.27606585904;48282.27576586354;48846.26730599044;49088;1068.6580294305747;1079.4063265204106;1153.3022521374448;1173.4484792147678;1190.2263528706237;27940.58089128624;28682.856585716938;28915.85542072276;29030.709692903645;29524.114276571738;41012.79493602513;41977.370339445544;43870.34194487149;43930.780346098065;44104.77947610241;381910;386023;386795;386912;387384;22492.662610060535;23180.377373583688;23316.533669326178;24023.27930162098;24045.27864164078;42012.57987420209;43377;43748.12503749971;44510.3323450155;44680;36700;36914;37453;38474.84229573392;38560;36689.44965825461;37247.255054899295;39059.80470097745;39353;39889.80055099706;36707.26585468329;36826.63173368339;36866.07834804044;36872.26255474822;37028.25943481169;30701.692983069883;32860.50709239411;32930.835345823114;33565.16087097842;33680.83159584186;24948.37629059288;24969.250922472354;26062;26074.214369376772;26455.33861653474
22:01:36 INFO - PROCESS | 3540 | |2;jquery.html;278.8410605954604;291.5393677988778;306.17485875565353;308.4109351139323;309.09589450856254;13367;13947.790783138249;14163.008589398733;14165.150090994548;14183;13950.162990220568;14241.71516569668;14389.561043895605;17033.7444938326;17306.48080557585;5180.093483640367;5362.27609272749;5373.838784836442;5378.300820893279;5378.327709036371;5114.2072978688175;5229.084910140729;5298.682079075268;5305.628605997563;5320.255164277004;3942.5860284670125;4109.178164367124;5253.159494480884;5265.65773224741;5808.6224395414165;6003.399660034005;6021.187139736146;6112.022076467766;6116.877662446771;6120.571559990803;4928.310036594879;4991.450940396553;5037.219231019198;5194.844154675366;5227.869303267411;21219.8939005304;21410;21778.34664960054;22104.889475552518;22996.425089372733;2338.6492026196206;2614.5947378156416;2718.592211168321;2735.3845384788265;2807.6069350290977;13697.452101916011;14424.49514267006;14587.27063646824;14717.632059198606;14760.926195369062;1693.322670931625;1734.5143359859132;1736.2794440307257;1754.359658724569;1812.5378028602622;79550.20449795485;84265.57867210686;84287.99999999939;84298.57850710707;84938.57530712307;8309.37679674027;8413.579321034047;8437.776522404218;8680.34897382699;8753.431026983226;11009.779804403866;11028.724281893017;11056.281341712924;11085.778284434427;11099.445027748741;6986.371226589631;7009.894851577231;7031.226565077837;7041.718331266692;7161.033260509868;7099.680514376865;7245.420366370678;7294.9270507295305;7316.341529262392;7394.2975417335265;5574.470425309671;5681.715914204273;5683.090705487102;5687.345955215121;5709.25779648643;1439.9640008999859;1462.4004158295047;1466.8679818816358;1469.3681716861852;1471.3746657670447;798.4490701416005;833.4457585705559;860.2043110123111;928;956.7321150077989;17809.7328540072;18480.630387392444;19143.329983450512;19194.424167275003;19267;874.1826392323198;899.9370044096981;901.7700486376064;912.9634814607474;948.7296120605595;10369.896301037044;10640;10889.128869690407;11023.283486573348;11043.226974111896;390570;397847;398248;399807;401701;90051;90258.54870725605;91195;91851.54074229719;92686;888.6889588644052;891.0243283604443;916.1159481100636;916.862470629411;920.9585568649293;16815.579610509834;16820.57948551296;16854.915725421295;17025.829741702426;17214.39749608758;40902.59097408988;42284.36573451339;44547.331790023825;44661.330080048174;45011.273816250134;55570.444295556525;58375;58505.12242316284;59122.70438647951;59769.402305978125;6166.876662466815;6289.905651415141;6359.586626869239;6388.712507937061;6485.6108633482145;10396.844047339448;12471.18937269111;12773.744525109632;12834.331536900143;12891.355432228538;10481.109105726098;10606.787864242826;10710.518026688662;10736.785264294827;10915.727106822393;10693;10710.089642380484;10803.189760768131;10952.616658416917;11011.779764404508;10822.960916535627;10920.12335620843;11053.502592383524;11077.11383089367;11130.666080017612;6829.31706829333;7209.6395180241825;7362.3373896349585;7376.852462950818;7425.59159246225;7375.262473752582;9581.760455988657;10621;10624.84062739075;10751.946240268748
22:01:36 INFO - PROCESS | 3540 | |3;mootools.html;1144.3591588710328;1248.4007676315368;1251.899848012159;1292.3538230884558;1496.678214183951;6054.636721796695;6813.318668133191;7058.752943646973;7074.893876591849;7175.282471752836;7021.929780702191;7096.751613693522;7097.680604372803;7100.538464999784;7111.85776284474;1876.8217019383137;1892.5457890106345;1894.412732053061;1896.1467339697122;1909.9618007639838;1773.184335205807;1848.9630207395844;1862.580919293155;1862.981370186301;1872.4008317338457;1870.7661542307212;1879.548908262014;1883.4537983984696;1889.518172865924;1899.9525011874744;1809.6199798042362;1842.106578309519;1861.5253110456815;1863.2360732099773;1863.4968558489131;1782.8217178282198;1798.6222893192446;1813.238439855264;1820.0353812479407;1835.0549467024493;6506.251781045193;6697.062411262427;6921.688524016431;6954.165500140016;6967.756128535528;4053.8783836484954;4107.732997355162;4205.032842446224;4208.347706105558;4210.010647497836;2749.1202815099177;2860.985695071532;2871.1530098620956;2890.6531216253975;2900.2459360566195;846.318713435684;855.4738835616114;871.4074429388014;881.823635272948;882.7219425880891;8246.299064579518;8479.533625650587;8499.66001359951;8821.382503224777;8827.955860220658;3430.468277417004;3461.9653803461642;3508.0703613542432;3514.6661067198575;3541.0793193769805;6866.279040700654;7105.218425973138;7105.46708996817;7110.857782844418;7112.613040457165;3960.029792700807;4040.2525532775944;4063.2686116499312;4113.341865301538;4128.4632997710105;3468.4103702370658;3571.7499775016017;3923.9019024524614;3927.4697915781576;3927.8232479538487;2974.3902499987453;3010.7440867526507;3033.9241518962203;3080.6919308069014;3099.969000310013;874.5233847553098;882.969096081634;883.0727735877304;884.5046773806561;889.5907882374051;765.5138986743458;793.131520984521;799.3165843203977;804.0672819529435;827.9172082791673;4444.155610434007;4583.129205450954;4593.563611456906;4603.930941035887;4662.650301227422;817.9059408168137;842.0737189092072;864.567716141929;898.5731777405811;901.2384535067857;2902.7097290271226;3054.205906464291;3076.09255269696;3079.445699774017;3090.752739780812;7992.600369981477;8239.134890836496;8317.459365141247;8404.243618074403;8484.32125429964;8354.58227088643;8425;8435.283000944866;8448.873266901;8461.576921153917;6537.842511971038;6559.540832141704;6651.900221496681;6679;6693.866122677519;4187.476565429321;4239.9364009540495;4345.283028300295;4502.594766471033;4568.223402021731;7561;7769.261920117579;7881.960590197013;7911.564863932539;7913.802154946173;7651.081870175614;7799.220077992155;7912.36701063902;7943.563104029334;8005.39959503028;5991.700414979146;6033.215681961317;6150.969245153746;6155.84610384744;6196.225471816023;6131.448169664752;6310.716017779303;6342.556021078481;6458.451031662361;6568.408843204134;6187.504999599932;6203.100550420254;6318.0838778375955;6381.936180638134;6412.166418365583;6155.569110162246;6208.348123446973;6302.558820882494;6345.17512723343;6434.806955791334;5592.664440133605;5674.943250567607;5726.169705392778;5739.2252045973255;5770.105633626795;4009.939850902297;4076.8776936691947;4080.510338759368;4117.855875044359;4275.743455392686;4105.794710264535;4158.209940111369;4161.334186530141;4166.520850102277;4173.248815213291
22:01:36 INFO - PROCESS | 3540 | |4;prototype.html;239.92082612737798;254.77707006369423;256.80097924108816;258.64436399950057;259.2572280416607;5189.45510721374;5190.429052804198;5195.896082078356;5219.217117432387;5233.947660523394;4754.263089221167;4937.679050861691;4939.555440010398;5027.648064635477;5033.245013248014;3702.9444558331647;3749.0252534340993;3766.510353654021;3774.565924918642;3793;3648.2521083177885;3754.7747135171976;3759.7556158849725;3790.469334293201;3795.0891785971444;3579.319929213441;3588.7308451866083;3636.727245456588;3653.2693461307713;3694.722895782814;3637.381645120336;3648.3980143276463;3683.410654295327;3747.381682022463;3753.193063491335;3096.9690303096904;3194.4888817789274;3251.7723759336855;3255.951160732591;3262.13553408347;4962.280469331927;5049.116404629175;5054.093896009809;5054.241863720435;5074.974625126851;4087.9182416351505;4233.306677329093;4368.847090351855;4375;4400.1519361868095;5304.6286759926825;5309.681419114827;5310.20346947957;5316.654417462892;5321.707306098163;3309.834508274577;3417.7436692247934;3444.5522082129414;3446.362422951762;3479.2867462170193;5550.139728342114;5582.8325150245555;5584.162375643646;5588.3573389060375;5599.440055994409;3335.24956884699;3511.2801875615946;3535.646435356444;3541.203229273393;3543.7342199335153;3523.788572685647;3532.8586856525453;3538.0447279234977;3542.8051457169586;3544.0608238816735;4080.2859499587453;4181.393697913785;4194.224068547267;4227.365895115696;4231.693726918837;5168.431472538018;5309.415964244007;5345.251664766935;5370.785168593291;5372.91940620891;1219.7804395208948;1237.8143278508296;1241.1498123785236;1242.3788105947026;1243.6517775023008;733.4938892164389;758.3288789421401;765.1583258415809;765.6592816196851;772.7295446593647;5062.215356619729;5083.212102124176;5117.590592752571;5142.51146141116;5195;5471.179323101566;5487.945120548823;5514.91727624086;5518.365387980353;5519.69641669712;5145.408278047998;5152.098382782998;5160.967806438727;5172.267290457664;5195.454477279907;2817.7150512198514;2824.5198316286283;2892.421515696869;2909.4617495762927;2919.605853209832;5759.366469688331;5816.534677225812;5837.562182836305;5844.736986835603;5868.383819698955;5558;5558.944410555923;5579.321925475794;5582;5582.972085139549;5616.728663100026;5828.300603927556;5829.921464529076;5847.976604094267;5853.678047707332;5213.1137706590725;5249.343832020997;5437.102878025082;5483.670979741229;5493.258410114583;5333.893322133458;5377.435369286169;5389.62272640927;5392.272043274108;5403.891922161613;5675.205471233948;5789.56578256624;5790.913136303043;5804.2744656917885;5806.44838740319;3514.0336407488144;3533.469979503095;3546.4503002034726;3555.5555555555557;3558.146044949142;5291.814786482455;5447.782088716565;5487.6158668892795;5508.779648814163;5512.39363669988;5222.216667499753;5324.920126198188;5337.946620533745;5344.198370244494;5351.598630102836;5072.18142764368;5305.9204111939125;5321.574274058144;5334.333208348957;5346.518813306821;4980.626453016111;5193.68880848019;5199.324087868553;5208.401033881;5214.452482489284;5214.530692237717;5334.75993580282;5337.733113344395;5350.117230656898;5363.812266570651;5235.188545775411;5266.183741520071;5268.157094864803;5370.489803468664;5387.703676297684
22:01:36 INFO - PROCESS | 3540 | |5;yui.html;654.6105067484846;666.4868051600268;711.7829062136049;723.02030748336;726.4043484342834;14261.572152835419;15468.917175797684;15603.453879114233;15730.685386292282;16551.420700275492;14904.627384315398;15231.162286074261;15362.923185384085;15392.153431561259;15428.228588570555;519.1175002495753;634.6636282770116;641.1792905081503;641.5637366590717;647.9157709497782;529.5631104338917;637.7289651897948;644.2204932032223;649.8667773106501;651.1404945471979;680.9591424514545;683.1494788987717;684.1550684904139;686.1800148822159;686.9690863911111;542.0459990416895;685.0546246180264;685.9691313890887;689.0078287266346;689.0972825598482;402.8086658837058;479.9664023518355;489.43714728062656;491.2680106641115;493.2084005171719;13852.168869867739;14144.929275353661;14159.362828672734;14169.716605667933;14243.07420017706;12441.564545240964;12678.027906606094;12915;13257.535986240531;13423.597292081253;12965.027622928224;13288.933555332258;13457.663558411117;13580.796288055688;14111.647208819759;3581.826027009989;3837.5586807517216;4368.453943257093;4386.385905973167;4517.367568540407;75531.24468755297;79701.20298796995;81114.18885811126;81709.59145204235;82293.58853205756;10883.401412922365;10896;11014.559417623366;11122.888771112186;11161.720956975978;18489;19891.900540497205;20181.39455816328;20218.696719549218;20301.08645110944;7488.850222995618;7613.619319034026;7977.641006154737;8049.315808156255;8301;4337.850469625551;4356.477222733229;4405.29515277554;4425.026494171278;4429.224885645;2909.6072030276064;2980.7764417668764;3016.3213277012496;3037.772167087477;3045.1778019934495;539.4282061015258;547.8657728856456;557.2143277978032;557.5456003357251;558.1655425139443;592.8014115271433;646.9850496685424;659.983500412484;682.5631595778706;692.8441100752291;9921.801563968824;9952.900470995342;9954.104130628279;10052.69841904744;10384.740381490523;545.3837164004649;558.9636673616284;646.5829539946802;657.7500549791049;708.9397401220954;4819.879503012453;4984.925226121611;5096.311997880312;5112.309838171874;5112.934708998874;63248.68375658184;102938.32515466263;110306;116066;117386;54944.72527637416;81158;90835.18736094687;91107;92858.89251596009;78886;81840.59079704683;82754.93112672112;83169.16830831736;84427.5778621103;9558.952205239208;9564.80439945007;9917.950410247902;9955.601775928882;10054.89945100569;46712.29931550961;46979;47081;48176.27735583899;49624.255636164766;64616.676916617;69912.65043674748;70263.29736702568;70275.45868119823;70722.64638676979;12320.445579948744;12455.501779929064;12693.619191424272;12746.553870614483;12836.55072072473;14309;14324.928375358057;14553.92723036378;14700.632484187981;15773.842261577238;13536.932315338361;13938.58184254474;14303.785443218569;14394.20831854258;14558;12868.906142977952;13014.934925325313;13017.284049376996;13108.082434229513;13170.407331669907;13565.796513052115;14656.560303190921;14774.852251477778;14797;14980.625484362543;11909.52361905514;12265.26408415498;12369.424142358514;12408.56570020045;12776.297303648389;10942;11481.540738370371;11673.474693638977;11694.590689325832;11777
22:01:36 INFO - PROCESS | 3540 | __end_tp_report
22:01:36 INFO - PROCESS | 3540 | __start_cc_report
22:01:36 INFO - PROCESS | 3540 | _x_x_mozilla_cycle_collect,980
22:01:36 INFO - PROCESS | 3540 | __end_cc_report
22:01:36 INFO - PROCESS | 3540 | __startTimestamp1474866096178__endTimestamp
22:01:36 INFO - PROCESS | 3540 |
22:01:36 INFO - PROCESS | 3540 | ------- Summary: start -------
22:01:36 INFO - PROCESS | 3540 | Number of tests: 6
22:01:36 INFO - PROCESS | 3540 |
22:01:36 INFO - PROCESS | 3540 | [#0] dojo.html Cycles:165 Average:4593.29 Median:4824.25 stddev:594.87 (12.3%) stddev-sans-first:577.69
22:01:36 INFO - PROCESS | 3540 | Values: 2691.7 2729.6 2769.6 2780.0 2780.5 4719.1 4748.2 4774.8 4774.8 4850.4 4556.0 4669.6 4703.0 4727.7 4757.7 4050.2 4142.5 4180.5 4187.4 4212.4 4141.9 4164.5 4191.8 4208.6 4223.7 4080.5 4179.6 4253.7 4260.9 4261.4 3827.2 4083.8 4111.9 4226.3 4232.9 3835.3 4078.0 4080.3 4104.2 4168.8 4560.4 4561.0 4655.8 4674.9 4681.1 4793.0 4824.3 4856.1 4944.6 4962.8 4871.7 4932.4 4975.1 4990.9 5028.1 4480.1 4576.2 4645.6 4670.2 4674.4 5014.9 5021.4 5119.9 5129.7 5141.1 4793.5 4828.6 4832.1 4839.6 4897.6 4905.5 4924.0 4956.4 4957.6 4961.2 4934.4 4939.5 4941.1 4948.3 4952.3 4907.4 4948.3 4952.1 4953.8 4958.2 3306.6 3312.6 3336.6 3343.0 3359.0 2969.7 3041.1 3077.2 3078.3 3080.1 4690.2 4696.7 4780.7 4786.3 4787.5 4904.0 4954.9 4963.2 4999.4 5017.5 4738.2 4774.5 4785.7 4787.9 4788.1 4105.7 4142.9 4168.1 4171.6 4204.6 5204.8 5286.7 5301.9 5368.3 5413.8 4978.1 4979.3 5048.1 5087.4 5100.4 5192.8 5202.3 5219.3 5237.8 5268.9 4861.6 4866.8 4959.9 4964.5 4970.4 4949.9 4962.2 5050.9 5067.6 5068.7 4824.8 4846.4 4849.1 4878.6 4919.2 4845.1 4919.7 4952.0 4952.3 4966.2 4768.2 4768.2 4833.7 4859.2 4881.2 4747.1 4778.5 4853.9 4859.8 4965.3 4753.6 4800.8 4882.5 4890.9 4894.3
22:01:36 INFO - PROCESS | 3540 |
22:01:36 INFO - PROCESS | 3540 | [#1] ext.html Cycles:180 Average:54453.09 Median:20483.06 stddev:107587.78 (525.3%) stddev-sans-first:107813.39
22:01:36 INFO - PROCESS | 3540 | Values: 521.4 550.4 559.8 582.5 594.4 29007.3 29887.6 31102.8 40337.6 40639.0 40832.4 41361.6 41432.8 41749.4 41909.8 7665.5 8167.2 8315.1 8611.4 8618.8 6396.0 6475.4 6604.2 6663.7 9869.5 9556.8 9690.6 9881.1 10234.5 10381.9 9744.6 10082.3 10168.7 10432.3 10601.5 6741.3 6863.3 9834.5 10382.0 10631.3 38921.8 39204.0 39312.2 39381.4 39392.8 3087.4 3114.3 3272.3 3316.7 3530.0 31343.5 31511.2 31777.0 31893.5 32267.0 3597.5 3688.4 3690.9 3718.9 3748.7 324338.0 332198.0 332444.0 333620.0 334956.3 52612.7 53022.0 53236.0 53300.0 53361.0 54716.5 54881.5 55385.0 55470.0 55495.7 13433.5 13516.4 13916.2 13948.7 13954.4 10310.6 10507.7 10516.9 10536.5 10568.8 13573.7 13867.9 15320.5 15684.6 15862.6 6224.9 6227.3 6255.6 6261.7 6284.3 818.5 919.5 925.1 979.9 1002.3 28644.4 28889.7 28907.3 29224.0 29644.7 921.0 928.1 933.2 1061.9 1116.1 14049.2 14504.0 14619.8 14683.4 14947.9 481709.0 482826.0 483296.0 486910.0 491704.0 47696.8 48262.3 48282.3 48846.3 49088.0 1068.7 1079.4 1153.3 1173.4 1190.2 27940.6 28682.9 28915.9 29030.7 29524.1 41012.8 41977.4 43870.3 43930.8 44104.8 381910.0 386023.0 386795.0 386912.0 387384.0 22492.7 23180.4 23316.5 24023.3 24045.3 42012.6 43377.0 43748.1 44510.3 44680.0 36700.0 36914.0 37453.0 38474.8 38560.0 36689.4 37247.3 39059.8 39353.0 39889.8 36707.3 36826.6 36866.1 36872.3 37028.3 30701.7 32860.5 32930.8 33565.2 33680.8 24948.4 24969.3 26062.0 26074.2 26455.3
22:01:36 INFO - PROCESS | 3540 |
22:01:36 INFO - PROCESS | 3540 | [#2] jquery.html Cycles:180 Average:25818.04 Median:23196.77 stddev:66385.81 (286.2%) stddev-sans-first:66544.35
22:01:36 INFO - PROCESS | 3540 | Values: 278.8 291.5 306.2 308.4 309.1 13367.0 13947.8 14163.0 14165.2 14183.0 13950.2 14241.7 14389.6 17033.7 17306.5 5180.1 5362.3 5373.8 5378.3 5378.3 5114.2 5229.1 5298.7 5305.6 5320.3 3942.6 4109.2 5253.2 5265.7 5808.6 6003.4 6021.2 6112.0 6116.9 6120.6 4928.3 4991.5 5037.2 5194.8 5227.9 21219.9 21410.0 21778.3 22104.9 22996.4 2338.6 2614.6 2718.6 2735.4 2807.6 13697.5 14424.5 14587.3 14717.6 14760.9 1693.3 1734.5 1736.3 1754.4 1812.5 79550.2 84265.6 84288.0 84298.6 84938.6 8309.4 8413.6 8437.8 8680.3 8753.4 11009.8 11028.7 11056.3 11085.8 11099.4 6986.4 7009.9 7031.2 7041.7 7161.0 7099.7 7245.4 7294.9 7316.3 7394.3 5574.5 5681.7 5683.1 5687.3 5709.3 1440.0 1462.4 1466.9 1469.4 1471.4 798.4 833.4 860.2 928.0 956.7 17809.7 18480.6 19143.3 19194.4 19267.0 874.2 899.9 901.8 913.0 948.7 10369.9 10640.0 10889.1 11023.3 11043.2 390570.0 397847.0 398248.0 399807.0 401701.0 90051.0 90258.5 91195.0 91851.5 92686.0 888.7 891.0 916.1 916.9 921.0 16815.6 16820.6 16854.9 17025.8 17214.4 40902.6 42284.4 44547.3 44661.3 45011.3 55570.4 58375.0 58505.1 59122.7 59769.4 6166.9 6289.9 6359.6 6388.7 6485.6 10396.8 12471.2 12773.7 12834.3 12891.4 10481.1 10606.8 10710.5 10736.8 10915.7 10693.0 10710.1 10803.2 10952.6 11011.8 10823.0 10920.1 11053.5 11077.1 11130.7 6829.3 7209.6 7362.3 7376.9 7425.6 7375.3 9581.8 10621.0 10624.8 10751.9
22:01:36 INFO - PROCESS | 3540 |
22:01:36 INFO - PROCESS | 3540 | [#3] mootools.html Cycles:180 Average:4492.53 Median:6012.46 stddev:2474.29 (41.2%) stddev-sans-first:2468.43
22:01:36 INFO - PROCESS | 3540 | Values: 1144.4 1248.4 1251.9 1292.4 1496.7 6054.6 6813.3 7058.8 7074.9 7175.3 7021.9 7096.8 7097.7 7100.5 7111.9 1876.8 1892.5 1894.4 1896.1 1910.0 1773.2 1849.0 1862.6 1863.0 1872.4 1870.8 1879.5 1883.5 1889.5 1900.0 1809.6 1842.1 1861.5 1863.2 1863.5 1782.8 1798.6 1813.2 1820.0 1835.1 6506.3 6697.1 6921.7 6954.2 6967.8 4053.9 4107.7 4205.0 4208.3 4210.0 2749.1 2861.0 2871.2 2890.7 2900.2 846.3 855.5 871.4 881.8 882.7 8246.3 8479.5 8499.7 8821.4 8828.0 3430.5 3462.0 3508.1 3514.7 3541.1 6866.3 7105.2 7105.5 7110.9 7112.6 3960.0 4040.3 4063.3 4113.3 4128.5 3468.4 3571.7 3923.9 3927.5 3927.8 2974.4 3010.7 3033.9 3080.7 3100.0 874.5 883.0 883.1 884.5 889.6 765.5 793.1 799.3 804.1 827.9 4444.2 4583.1 4593.6 4603.9 4662.7 817.9 842.1 864.6 898.6 901.2 2902.7 3054.2 3076.1 3079.4 3090.8 7992.6 8239.1 8317.5 8404.2 8484.3 8354.6 8425.0 8435.3 8448.9 8461.6 6537.8 6559.5 6651.9 6679.0 6693.9 4187.5 4239.9 4345.3 4502.6 4568.2 7561.0 7769.3 7882.0 7911.6 7913.8 7651.1 7799.2 7912.4 7943.6 8005.4 5991.7 6033.2 6151.0 6155.8 6196.2 6131.4 6310.7 6342.6 6458.5 6568.4 6187.5 6203.1 6318.1 6381.9 6412.2 6155.6 6208.3 6302.6 6345.2 6434.8 5592.7 5674.9 5726.2 5739.2 5770.1 4009.9 4076.9 4080.5 4117.9 4275.7 4105.8 4158.2 4161.3 4166.5 4173.2
22:01:36 INFO - PROCESS | 3540 |
22:01:36 INFO - PROCESS | 3540 | [#4] prototype.html Cycles:180 Average:4398.86 Median:5180.86 stddev:1402.30 (27.1%) stddev-sans-first:1371.05
22:01:36 INFO - PROCESS | 3540 | Values: 239.9 254.8 256.8 258.6 259.3 5189.5 5190.4 5195.9 5219.2 5233.9 4754.3 4937.7 4939.6 5027.6 5033.2 3702.9 3749.0 3766.5 3774.6 3793.0 3648.3 3754.8 3759.8 3790.5 3795.1 3579.3 3588.7 3636.7 3653.3 3694.7 3637.4 3648.4 3683.4 3747.4 3753.2 3097.0 3194.5 3251.8 3256.0 3262.1 4962.3 5049.1 5054.1 5054.2 5075.0 4087.9 4233.3 4368.8 4375.0 4400.2 5304.6 5309.7 5310.2 5316.7 5321.7 3309.8 3417.7 3444.6 3446.4 3479.3 5550.1 5582.8 5584.2 5588.4 5599.4 3335.2 3511.3 3535.6 3541.2 3543.7 3523.8 3532.9 3538.0 3542.8 3544.1 4080.3 4181.4 4194.2 4227.4 4231.7 5168.4 5309.4 5345.3 5370.8 5372.9 1219.8 1237.8 1241.1 1242.4 1243.7 733.5 758.3 765.2 765.7 772.7 5062.2 5083.2 5117.6 5142.5 5195.0 5471.2 5487.9 5514.9 5518.4 5519.7 5145.4 5152.1 5161.0 5172.3 5195.5 2817.7 2824.5 2892.4 2909.5 2919.6 5759.4 5816.5 5837.6 5844.7 5868.4 5558.0 5558.9 5579.3 5582.0 5583.0 5616.7 5828.3 5829.9 5848.0 5853.7 5213.1 5249.3 5437.1 5483.7 5493.3 5333.9 5377.4 5389.6 5392.3 5403.9 5675.2 5789.6 5790.9 5804.3 5806.4 3514.0 3533.5 3546.5 3555.6 3558.1 5291.8 5447.8 5487.6 5508.8 5512.4 5222.2 5324.9 5337.9 5344.2 5351.6 5072.2 5305.9 5321.6 5334.3 5346.5 4980.6 5193.7 5199.3 5208.4 5214.5 5214.5 5334.8 5337.7 5350.1 5363.8 5235.2 5266.2 5268.2 5370.5 5387.7
22:01:36 INFO - PROCESS | 3540 |
22:01:36 INFO - PROCESS | 3540 | [#5] yui.html Cycles:180 Average:19675.20 Median:4427.13 stddev:27556.12 (622.4%) stddev-sans-first:27596.41
22:01:36 INFO - PROCESS | 3540 | Values: 654.6 666.5 711.8 723.0 726.4 14261.6 15468.9 15603.5 15730.7 16551.4 14904.6 15231.2 15362.9 15392.2 15428.2 519.1 634.7 641.2 641.6 647.9 529.6 637.7 644.2 649.9 651.1 681.0 683.1 684.2 686.2 687.0 542.0 685.1 686.0 689.0 689.1 402.8 480.0 489.4 491.3 493.2 13852.2 14144.9 14159.4 14169.7 14243.1 12441.6 12678.0 12915.0 13257.5 13423.6 12965.0 13288.9 13457.7 13580.8 14111.6 3581.8 3837.6 4368.5 4386.4 4517.4 75531.2 79701.2 81114.2 81709.6 82293.6 10883.4 10896.0 11014.6 11122.9 11161.7 18489.0 19891.9 20181.4 20218.7 20301.1 7488.9 7613.6 7977.6 8049.3 8301.0 4337.9 4356.5 4405.3 4425.0 4429.2 2909.6 2980.8 3016.3 3037.8 3045.2 539.4 547.9 557.2 557.5 558.2 592.8 647.0 660.0 682.6 692.8 9921.8 9952.9 9954.1 10052.7 10384.7 545.4 559.0 646.6 657.8 708.9 4819.9 4984.9 5096.3 5112.3 5112.9 63248.7 102938.3 110306.0 116066.0 117386.0 54944.7 81158.0 90835.2 91107.0 92858.9 78886.0 81840.6 82754.9 83169.2 84427.6 9559.0 9564.8 9918.0 9955.6 10054.9 46712.3 46979.0 47081.0 48176.3 49624.3 64616.7 69912.7 70263.3 70275.5 70722.6 12320.4 12455.5 12693.6 12746.6 12836.6 14309.0 14324.9 14553.9 14700.6 15773.8 13536.9 13938.6 14303.8 14394.2 14558.0 12868.9 13014.9 13017.3 13108.1 13170.4 13565.8 14656.6 14774.9 14797.0 14980.6 11909.5 12265.3 12369.4 12408.6 12776.3 10942.0 11481.5 11673.5 11694.6 11777.0
22:01:36 INFO - PROCESS | 3540 | -------- Summary: end --------
22:01:36 INFO - PROCESS | 3540 |
22:01:36 INFO - TEST-INFO | 3540: exit 0
22:01:37 INFO - TEST-OK | dromaeo_css | took 1118648ms
22:01:37 INFO - TEST-START | kraken
22:01:37 INFO - operating with platform_type : w7_
22:01:37 INFO - Initialising browser for kraken test...
22:01:37 INFO - TEST-INFO | started process 3680 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpikl6fj\profile http://localhost:49897/getInfo.html)
22:01:42 INFO - PROCESS | 3680 | __metrics Screen width/height:1600/1200
22:01:42 INFO - PROCESS | 3680 | colorDepth:24
22:01:42 INFO - PROCESS | 3680 | Browser inner width/height: 1010/674
22:01:42 INFO - PROCESS | 3680 | __metrics
22:01:42 INFO - PROCESS | 3680 | 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"
22:01:42 INFO - PROCESS | 3680 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
22:01:43 INFO - PROCESS | 3680 | console.error:
22:01:43 INFO - PROCESS | 3680 | Message: Error: Connection closed before committing the transaction.
22:01:43 INFO - PROCESS | 3680 | Stack:
22:01:43 INFO - PROCESS | 3680 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:937:23
22:01:43 INFO - PROCESS | 3680 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7
22:01:43 INFO - PROCESS | 3680 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11
22:01:43 INFO - PROCESS | 3680 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7
22:01:43 INFO - PROCESS | 3680 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7
22:01:43 INFO - PROCESS | 3680 | TaskImpl_run@resource://gre/modules/Task.jsm:324:13
22:01:43 INFO - PROCESS | 3680 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9
22:01:43 INFO - PROCESS | 3680 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15
22:01:43 INFO - PROCESS | 3680 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5
22:01:43 INFO - PROCESS | 3680 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29
22:01:43 INFO - PROCESS | 3680 | Barrier/this.client.addBlocker/promise13.3%
22:02:41 INFO - TinderboxPrint: I/O read bytes / time
451,272,704 / 2,895,360
22:02:41 INFO - TinderboxPrint: I/O write bytes / time
515,812,352 / 3,856,540
22:02:41 INFO - TinderboxPrint: CPU idle
8,225.2 (86.7%)
22:02:41 INFO - TinderboxPrint: CPU user
1,214.9 (12.8%)
22:02:41 INFO - install - Wall time: 3s; CPU: 11.0%; Read bytes: 0; Write bytes: 35300864; Read time: 0; Write time: 355340
22:02:41 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 451272704; Write bytes: 443530240; Read time: 2895360; Write time: 3410320
22:02:41 INFO - Running post-run listener: _upload_blobber_files
22:02:41 INFO - Blob upload gear active.
22:02:41 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir.
22:02:41 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
22:02:41 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\venv\\Scripts\\blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', 'C:\\slave\\test\\oauth.txt', '-b', 'Autoland', '-d', 'C:\\slave\\test\\build\\blobber_upload_dir', '--output-manifest', 'C:\\slave\\test\\build\\uploaded_files.json']
22:02:41 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\venv\Scripts\blobberc.py -u https://blobupload.elasticbeanstalk.com -a C:\slave\test\oauth.txt -b Autoland -d C:\slave\test\build\blobber_upload_dir --output-manifest C:\slave\test\build\uploaded_files.json
22:02:42 INFO - (blobuploader) - INFO - Open directory for files ...
22:02:42 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log ...
22:02:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
22:02:42 INFO - (blobuploader) - INFO - Uploading, attempt #1.
22:02:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded
22:02:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
22:02:42 INFO - (blobuploader) - INFO - Done attempting.
22:02:42 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log ...
22:02:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
22:02:42 INFO - (blobuploader) - INFO - Uploading, attempt #1.
22:02:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded
22:02:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
22:02:43 INFO - (blobuploader) - INFO - Done attempting.
22:02:43 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ...
22:02:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
22:02:43 INFO - (blobuploader) - INFO - Uploading, attempt #1.
22:02:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
22:02:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
22:02:51 INFO - (blobuploader) - INFO - Done attempting.
22:02:51 INFO - (blobuploader) - INFO - Iteration through files over.
22:02:51 INFO - Return code: 0
22:02:51 INFO - rmtree: C:\slave\test\build\uploaded_files.json
22:02:51 INFO - Using _rmtree_windows ...
22:02:51 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1
22:02:51 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ce68bec72a10fd84118461bfda1e1bfd656528b952919abb55181aa015131e77093cb70bc2957536648a8d45fc2cf0d962c8bebb2c019ff48ecee03f8193a492", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d42107ada90dfbac0db840eee0006b49963c82bd541a94aad5bdcfbf7d89843623b7c02c5ece2c7da7146b29f33cb8edb4ac0fbab0b3089bdcd2d30e93c71099", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c7a14f7b17c18d1d5a7585ec26ef2f32a40e8edcd93e3c8b83431030356a82753a7d76fc35ceae2610b5c73617b7bbfb98929224657cf9061c07350d113ced67"}
22:02:51 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files
22:02:51 INFO - Writing to file C:\slave\test\properties\blobber_files
22:02:51 INFO - Contents:
22:02:51 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ce68bec72a10fd84118461bfda1e1bfd656528b952919abb55181aa015131e77093cb70bc2957536648a8d45fc2cf0d962c8bebb2c019ff48ecee03f8193a492", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d42107ada90dfbac0db840eee0006b49963c82bd541a94aad5bdcfbf7d89843623b7c02c5ece2c7da7146b29f33cb8edb4ac0fbab0b3089bdcd2d30e93c71099", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c7a14f7b17c18d1d5a7585ec26ef2f32a40e8edcd93e3c8b83431030356a82753a7d76fc35ceae2610b5c73617b7bbfb98929224657cf9061c07350d113ced67"}
22:02:51 INFO - Running post-run listener: copy_logs_to_upload_dir
22:02:51 INFO - Copying logs to upload dir...
22:02:51 INFO - mkdir: C:\slave\test\build\upload\logs
22:02:51 INFO - Copying logs to upload dir...
22:02:51 INFO - Using _rmtree_windows ...
22:02:51 INFO - Using _rmtree_windows ...
22:02:51 INFO - Using _rmtree_windows ...
22:02:51 INFO - Using _rmtree_windows ...
22:02:51 INFO - Using _rmtree_windows ...
22:02:51 INFO - Using _rmtree_windows ...
22:02:51 INFO - Using _rmtree_windows ...
program finished with exit code 0
elapsedTime=1413.962000
========= master_lag: 0.07 =========
========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 34 secs) (at 2016-09-25 22:02:51.600385) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-25 22:02:51.601229) =========
'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-039
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-039
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-039
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/Autoland/sha512/ce68bec72a10fd84118461bfda1e1bfd656528b952919abb55181aa015131e77093cb70bc2957536648a8d45fc2cf0d962c8bebb2c019ff48ecee03f8193a492", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d42107ada90dfbac0db840eee0006b49963c82bd541a94aad5bdcfbf7d89843623b7c02c5ece2c7da7146b29f33cb8edb4ac0fbab0b3089bdcd2d30e93c71099", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c7a14f7b17c18d1d5a7585ec26ef2f32a40e8edcd93e3c8b83431030356a82753a7d76fc35ceae2610b5c73617b7bbfb98929224657cf9061c07350d113ced67"}
build_url:https://queue.taskcluster.net/v1/task/J4XRUAtCR3qHKk2_hM0uVA/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/J4XRUAtCR3qHKk2_hM0uVA/artifacts/public/build/firefox-52.0a1.en-US.win32.zip'
blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ce68bec72a10fd84118461bfda1e1bfd656528b952919abb55181aa015131e77093cb70bc2957536648a8d45fc2cf0d962c8bebb2c019ff48ecee03f8193a492", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d42107ada90dfbac0db840eee0006b49963c82bd541a94aad5bdcfbf7d89843623b7c02c5ece2c7da7146b29f33cb8edb4ac0fbab0b3089bdcd2d30e93c71099", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c7a14f7b17c18d1d5a7585ec26ef2f32a40e8edcd93e3c8b83431030356a82753a7d76fc35ceae2610b5c73617b7bbfb98929224657cf9061c07350d113ced67"}'
========= master_lag: 0.01 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-25 22:02:51.716434) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-25 22:02:51.716800) =========
'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-039
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-039
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-039
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-09-25 22:02:51.828120) =========
========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-09-25 22:02:51.828441) =========
========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-09-25 22:02:52.443081) =========
========= Total master_lag: 0.27 =========