builder: autoland_win7_ix_test-dromaeojs-pgo
slave: t-w732-ix-138
starttime: 1474823416.75
results: success (0)
buildid: 20160925063030
builduid: f2fec4e04e1e43ec8669f0e94f5a1a47
revision: 5011b0476532fbc5b36a8d75e4618c7d040526cd
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-25 10:10:16.755592) =========
master: http://buildbot-master127.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-25 10:10:16.757191) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-25 10:10:16.757630) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-25 10:10:16.969994) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-25 10:10:16.970306) =========
'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-138
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-138
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-138
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
--10:10:17-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
=> `archiver_client.py'
Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25
Connecting to hg.mozilla.org|63.245.215.102|:443... connected.
HTTP request sent, awaiting response... 200 Script output follows
Length: 12,141 (12K) [text/x-python]
0K .......... . 100% 2.27 GB/s
10:10:18 (2.27 GB/s) - `archiver_client.py' saved [12141/12141]
program finished with exit code 0
elapsedTime=1.035000
========= master_lag: 0.08 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-25 10:10:18.083668) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 11 secs) (at 2016-09-25 10:10:18.084483) =========
'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-138
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-138
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-138
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=11.801000
========= master_lag: 0.01 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 11 secs) (at 2016-09-25 10:10:29.898844) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-25 10:10:29.899649) =========
'bash' '-c' u'python archiver_client.py mozharness --repo integration/autoland --rev 5011b0476532fbc5b36a8d75e4618c7d040526cd --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 5011b0476532fbc5b36a8d75e4618c7d040526cd --destination scripts --debug']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-138
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-138
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-138
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 10:10:30,096 truncating revision to first 12 chars
2016-09-25 10:10:30,096 Setting DEBUG logging.
2016-09-25 10:10:30,096 attempt 1/10
2016-09-25 10:10:30,096 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/5011b0476532?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2016-09-25 10:10:30,655 unpacking tar archive at: autoland-5011b0476532/testing/mozharness/
2016-09-25 10:10:30,796 skipping symlink on windows: configs/single_locale/linux32.py
program finished with exit code 0
elapsedTime=1.095000
========= master_lag: 0.03 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-25 10:10:31.026281) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-25 10:10:31.026606) =========
script_repo_revision: 5011b0476532fbc5b36a8d75e4618c7d040526cd
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-25 10:10:31.027044) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-25 10:10:31.027370) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-25 10:10:31.038156) =========
========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 22 mins, 49 secs) (at 2016-09-25 10:10:31.038589) =========
'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-138
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-138
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-138
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
10:10:31 INFO - MultiFileLogger online at 20160925 10:10:31 in C:\slave\test
10:10:31 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
10:10:31 INFO - Dumping config to C:\slave\test\logs\localconfig.json.
10:10:31 INFO - {'append_to_log': False,
10:10:31 INFO - 'base_work_dir': 'C:\\slave\\test',
10:10:31 INFO - 'blob_upload_branch': 'Autoland',
10:10:31 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt',
10:10:31 INFO - 'branch': 'Autoland',
10:10:31 INFO - 'buildbot_json_path': 'buildprops.json',
10:10:31 INFO - 'config_files': ('talos/windows_config.py',),
10:10:31 INFO - 'default_actions': ('clobber',
10:10:31 INFO - 'read-buildbot-config',
10:10:31 INFO - 'download-and-extract',
10:10:31 INFO - 'populate-webroot',
10:10:31 INFO - 'create-virtualenv',
10:10:31 INFO - 'install',
10:10:31 INFO - 'run-tests'),
10:10:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
10:10:31 INFO - 'download_minidump_stackwalk': True,
10:10:31 INFO - 'download_symbols': 'ondemand',
10:10:31 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python',
10:10:31 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'),
10:10:31 INFO - 'hg': 'c:/mozilla-build/hg/hg',
10:10:31 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python',
10:10:31 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'),
10:10:31 INFO - 'python': 'c:/mozilla-build/python27/python.exe',
10:10:31 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe',
10:10:31 INFO - 'C:/mozilla-build/tooltool.py'),
10:10:31 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe',
10:10:31 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')},
10:10:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
10:10:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
10:10:31 INFO - 'installer_path': 'installer.exe',
10:10:31 INFO - 'log_level': 'info',
10:10:31 INFO - 'log_name': 'talos',
10:10:31 INFO - 'log_to_console': True,
10:10:31 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe',
10:10:31 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe',
10:10:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest',
10:10:31 INFO - 'opt_config_files': (),
10:10:31 INFO - 'pip_index': False,
10:10:31 INFO - 'sps_profile': False,
10:10:31 INFO - 'sps_profile_interval': 0,
10:10:31 INFO - 'suite': 'dromaeojs',
10:10:31 INFO - 'system_bits': '32',
10:10:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
10:10:31 INFO - 'title': 'T-W732-IX-138',
10:10:31 INFO - 'use_talos_json': True,
10:10:31 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'),
10:10:31 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv',
10:10:31 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll',
10:10:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
10:10:31 INFO - 'work_dir': 'build'}
10:10:31 INFO - [mozharness: 2016-09-25 17:10:31.561000Z] Running clobber step.
10:10:31 INFO - Running pre-action listener: _resource_record_pre_action
10:10:31 INFO - Running main action method: clobber
10:10:31 INFO - rmtree: C:\slave\test\build
10:10:31 INFO - Using _rmtree_windows ...
10:10:31 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1
10:11:41 INFO - Running post-action listener: _resource_record_post_action
10:11:41 INFO - [mozharness: 2016-09-25 17:11:41.507000Z] Finished clobber step (success)
10:11:41 INFO - [mozharness: 2016-09-25 17:11:41.507000Z] Running read-buildbot-config step.
10:11:41 INFO - Running pre-action listener: _resource_record_pre_action
10:11:41 INFO - Running main action method: read_buildbot_config
10:11:41 INFO - Using buildbot properties:
10:11:41 INFO - {
10:11:41 INFO - "project": "",
10:11:41 INFO - "product": "firefox",
10:11:41 INFO - "script_repo_revision": "production",
10:11:41 INFO - "builddir": "autoland_win7_ix_test-dromaeojs",
10:11:41 INFO - "repository": "",
10:11:41 INFO - "buildername": "Windows 7 32-bit autoland pgo talos dromaeojs",
10:11:41 INFO - "buildid": "20160925063030",
10:11:41 INFO - "pgo_build": "True",
10:11:41 INFO - "basedir": "C:\\slave\\test",
10:11:41 INFO - "buildnumber": 104,
10:11:41 INFO - "slavename": "t-w732-ix-138",
10:11:41 INFO - "master": "http://buildbot-master127.bb.releng.scl3.mozilla.com:8201/",
10:11:41 INFO - "platform": "win7_ix",
10:11:41 INFO - "scheduler": "tests-autoland-win32-pgo-talos",
10:11:41 INFO - "branch": "autoland",
10:11:41 INFO - "repo_path": "integration/autoland",
10:11:41 INFO - "revision": "5011b0476532fbc5b36a8d75e4618c7d040526cd",
10:11:41 INFO - "stage_platform": "win32-pgo",
10:11:41 INFO - "builduid": "f2fec4e04e1e43ec8669f0e94f5a1a47",
10:11:41 INFO - "slavebuilddir": "test"
10:11:41 INFO - }
10:11:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/HcsnZxEbQPiBMFYuFdrp8A/artifacts/public/build/firefox-52.0a1.en-US.win32.zip.
10:11:41 INFO - Running post-action listener: _resource_record_post_action
10:11:41 INFO - [mozharness: 2016-09-25 17:11:41.510000Z] Finished read-buildbot-config step (success)
10:11:41 INFO - [mozharness: 2016-09-25 17:11:41.511000Z] Running download-and-extract step.
10:11:41 INFO - Running pre-action listener: _resource_record_pre_action
10:11:41 INFO - Running main action method: download_and_extract
10:11:41 INFO - mkdir: C:\slave\test\build\tests
10:11:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
10:11:41 INFO - trying https://queue.taskcluster.net/v1/task/HcsnZxEbQPiBMFYuFdrp8A/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json
10:11:41 INFO - Downloading https://queue.taskcluster.net/v1/task/HcsnZxEbQPiBMFYuFdrp8A/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
10:11:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HcsnZxEbQPiBMFYuFdrp8A/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
10:11:42 INFO - Downloaded 1218 bytes.
10:11:42 INFO - Reading from file C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json
10:11:42 INFO - Using the following test package requirements:
10:11:42 INFO - {u'common': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
10:11:42 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
10:11:42 INFO - u'firefox-52.0a1.en-US.win32.cppunittest.tests.zip'],
10:11:42 INFO - u'gtest': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
10:11:42 INFO - u'jittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
10:11:42 INFO - u'jsshell-win32.zip'],
10:11:42 INFO - u'mochitest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
10:11:42 INFO - u'firefox-52.0a1.en-US.win32.mochitest.tests.zip'],
10:11:42 INFO - u'mozbase': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
10:11:42 INFO - u'reftest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
10:11:42 INFO - u'firefox-52.0a1.en-US.win32.reftest.tests.zip'],
10:11:42 INFO - u'talos': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
10:11:42 INFO - u'firefox-52.0a1.en-US.win32.talos.tests.zip'],
10:11:42 INFO - u'web-platform': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
10:11:42 INFO - u'firefox-52.0a1.en-US.win32.web-platform.tests.zip'],
10:11:42 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
10:11:42 INFO - u'firefox-52.0a1.en-US.win32.xpcshell.tests.zip']}
10:11:42 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip'] for test suite category: common
10:11:42 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/HcsnZxEbQPiBMFYuFdrp8A/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip
10:11:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HcsnZxEbQPiBMFYuFdrp8A/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1
10:11:42 INFO - Fetch https://queue.taskcluster.net/v1/task/HcsnZxEbQPiBMFYuFdrp8A/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory
10:11:43 INFO - Expected file size: 17291491
10:12:12 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip', u'firefox-52.0a1.en-US.win32.talos.tests.zip'] for test suite category: talos
10:12:12 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/HcsnZxEbQPiBMFYuFdrp8A/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip
10:12:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HcsnZxEbQPiBMFYuFdrp8A/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1
10:12:12 INFO - Fetch https://queue.taskcluster.net/v1/task/HcsnZxEbQPiBMFYuFdrp8A/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory
10:12:13 INFO - Expected file size: 17291491
10:12:19 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/HcsnZxEbQPiBMFYuFdrp8A/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip
10:12:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HcsnZxEbQPiBMFYuFdrp8A/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip'}, attempt #1
10:12:19 INFO - Fetch https://queue.taskcluster.net/v1/task/HcsnZxEbQPiBMFYuFdrp8A/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip into memory
10:12:20 INFO - Expected file size: 14414059
10:12: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')]}
10:12:34 INFO - trying https://queue.taskcluster.net/v1/task/HcsnZxEbQPiBMFYuFdrp8A/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
10:12:34 INFO - Downloading https://queue.taskcluster.net/v1/task/HcsnZxEbQPiBMFYuFdrp8A/artifacts/public/build/firefox-52.0a1.en-US.win32.zip to C:\slave\test\build\firefox-52.0a1.en-US.win32.zip
10:12:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HcsnZxEbQPiBMFYuFdrp8A/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
10:12:37 INFO - Downloaded 54748534 bytes.
10:12:37 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HcsnZxEbQPiBMFYuFdrp8A/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
10:12:37 INFO - mkdir: C:\slave\test\properties
10:12:37 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url
10:12:37 INFO - Writing to file C:\slave\test\properties\build_url
10:12:37 INFO - Contents:
10:12:37 INFO - build_url:https://queue.taskcluster.net/v1/task/HcsnZxEbQPiBMFYuFdrp8A/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
10:12:38 INFO - Running post-action listener: _resource_record_post_action
10:12:38 INFO - Running post-action listener: set_extra_try_arguments
10:12:38 INFO - [mozharness: 2016-09-25 17:12:38.327000Z] Finished download-and-extract step (success)
10:12:38 INFO - [mozharness: 2016-09-25 17:12:38.327000Z] Running populate-webroot step.
10:12:38 INFO - Running pre-action listener: _resource_record_pre_action
10:12:38 INFO - Running main action method: populate_webroot
10:12:38 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']},
10:12:38 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']},
10:12:38 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome',
10:12:38 INFO - u'--tppagecycles',
10:12:38 INFO - u'7'],
10:12:38 INFO - u'tests': [u'tsvgm']}},
10:12:38 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'],
10:12:38 INFO - u'tests': [u'tresize', u'tcanvasmark']},
10:12:38 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']},
10:12:38 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'],
10:12:38 INFO - u'tests': [u'dromaeo_css', u'kraken']},
10:12:38 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']},
10:12:38 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
10:12:38 INFO - u'talos_options': [u'--disable-e10s'],
10:12:38 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
10:12:38 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
10:12:38 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
10:12:38 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
10:12:38 INFO - u'talos_options': [u'--disable-e10s'],
10:12:38 INFO - u'tests': [u'damp', u'tps']},
10:12:38 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
10:12:38 INFO - u'tests': [u'damp', u'tps']},
10:12:38 INFO - u'g3': {u'talos_options': [u'--disable-e10s'],
10:12:38 INFO - u'tests': [u'dromaeo_dom']},
10:12:38 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']},
10:12:38 INFO - u'g4': {u'talos_options': [u'--disable-e10s'],
10:12:38 INFO - u'tests': [u'basic_compositor_video']},
10:12:38 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']},
10:12:38 INFO - u'other': {u'talos_options': [u'--disable-e10s'],
10:12:38 INFO - u'tests': [u'a11yr',
10:12:38 INFO - u'ts_paint',
10:12:38 INFO - u'tpaint',
10:12:38 INFO - u'sessionrestore',
10:12:38 INFO - u'sessionrestore_no_auto_restore',
10:12:38 INFO - u'tabpaint']},
10:12:38 INFO - u'other-e10s': {u'tests': [u'a11yr',
10:12:38 INFO - u'ts_paint',
10:12:38 INFO - u'tpaint',
10:12:38 INFO - u'sessionrestore',
10:12:38 INFO - u'sessionrestore_no_auto_restore',
10:12:38 INFO - u'tabpaint']},
10:12:38 INFO - u'other-e10s_l64': {u'tests': [u'a11yr',
10:12:38 INFO - u'ts_paint',
10:12:38 INFO - u'tpaint',
10:12:38 INFO - u'sessionrestore',
10:12:38 INFO - u'sessionrestore_no_auto_restore',
10:12:38 INFO - u'tabpaint']},
10:12:38 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr',
10:12:38 INFO - u'ts_paint',
10:12:38 INFO - u'tpaint',
10:12:38 INFO - u'sessionrestore',
10:12:38 INFO - u'sessionrestore_no_auto_restore',
10:12:38 INFO - u'tabpaint']},
10:12:38 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'],
10:12:38 INFO - u'tests': [u'a11yr',
10:12:38 INFO - u'ts_paint',
10:12:38 INFO - u'tpaint',
10:12:38 INFO - u'sessionrestore',
10:12:38 INFO - u'sessionrestore_no_auto_restore',
10:12:38 INFO - u'tabpaint']},
10:12:38 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'],
10:12:38 INFO - u'tests': [u'a11yr',
10:12:38 INFO - u'ts_paint',
10:12:38 INFO - u'tpaint',
10:12:38 INFO - u'sessionrestore',
10:12:38 INFO - u'sessionrestore_no_auto_restore',
10:12:38 INFO - u'tabpaint']},
10:12:38 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'],
10:12:38 INFO - u'tests': [u'tsvgx',
10:12:38 INFO - u'tsvgr_opacity',
10:12:38 INFO - u'tart',
10:12:38 INFO - u'tscrollx',
10:12:38 INFO - u'cart']},
10:12:38 INFO - u'svgr-e10s': {u'tests': [u'tsvgx',
10:12:38 INFO - u'tsvgr_opacity',
10:12:38 INFO - u'tart',
10:12:38 INFO - u'tscrollx',
10:12:38 INFO - u'cart']},
10:12:38 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
10:12:38 INFO - u'talos_options': [u'--disable-e10s'],
10:12:38 INFO - u'tests': [u'tp5o']},
10:12:38 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
10:12:38 INFO - u'tests': [u'tp5o']},
10:12:38 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
10:12:38 INFO - u'talos_options': [u'--disable-e10s',
10:12:38 INFO - u'--xperf_path',
10:12:38 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
10:12:38 INFO - u'tests': [u'tp5n']},
10:12:38 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
10:12:38 INFO - u'talos_options': [u'--xperf_path',
10:12:38 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
10:12:38 INFO - u'tests': [u'tp5n']}},
10:12:38 INFO - u'talos.zip': {u'path': u'',
10:12:38 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}}
10:12:38 INFO - Running post-action listener: _resource_record_post_action
10:12:38 INFO - [mozharness: 2016-09-25 17:12:38.429000Z] Finished populate-webroot step (success)
10:12:38 INFO - [mozharness: 2016-09-25 17:12:38.429000Z] Running create-virtualenv step.
10:12:38 INFO - Running pre-action listener: _resource_record_pre_action
10:12:38 INFO - Running main action method: create_virtualenv
10:12:38 INFO - Creating virtualenv C:\slave\test\build/venv
10:12:38 INFO - mkdir: C:\slave\test\build\venv\Scripts
10:12:38 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll
10:12:38 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'!
10:12:38 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
10:12:38 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv
10:12:42 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe
10:12:45 INFO - Installing distribute......................................................................................................................................................................................done.
10:12:47 INFO - Return code: 0
10:12:47 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version']
10:12:47 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version
10:12:47 INFO - Reading from file tmpfile_stdout
10:12:47 INFO - Output received:
10:12:47 INFO - pip 0.8.2 from c:\slave\test\build\venv\lib\site-packages\pip-0.8.2-py2.7.egg (python 2.7)
10:12:47 INFO - Using _rmtree_windows ...
10:12:47 INFO - Using _rmtree_windows ...
10:12:47 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv
10:12:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
10:12:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
10:12:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
10:12:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
10:12:47 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01861640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01909E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0180B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01928E90>, '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 0x0123A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019DE520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-138', 'USERDOMAIN': 'T-W732-IX-138', '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-138', '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
10:12:47 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in C:\slave\test\build
10:12:47 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5
10:12:47 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
10:12:47 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
10:12:47 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
10:12:47 INFO - 'COMPUTERNAME': 'T-W732-IX-138',
10:12:47 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
10:12:47 INFO - 'CYGWINBASE': 'C:\\cygwin',
10:12:47 INFO - 'DCLOCATION': 'SCL3',
10:12:47 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
10:12:47 INFO - 'FP_NO_HOST_CHECK': 'NO',
10:12:47 INFO - 'HOMEDRIVE': 'C:',
10:12:47 INFO - 'HOMEPATH': '\\Users\\cltbld',
10:12:47 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
10:12:47 INFO - 'KTS_VERSION': '1.19c',
10:12:47 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
10:12:47 INFO - 'LOGONSERVER': '\\\\T-W732-IX-138',
10:12:47 INFO - 'MONDIR': 'C:\\Monitor_config\\',
10:12:47 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
10:12:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
10:12:47 INFO - 'MOZ_NO_REMOTE': '1',
10:12:47 INFO - 'NO_EM_RESTART': '1',
10:12:47 INFO - 'NUMBER_OF_PROCESSORS': '8',
10:12:47 INFO - 'OS': 'Windows_NT',
10:12:47 INFO - 'OURDRIVE': 'C:',
10:12:47 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
10:12:47 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
10:12:47 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
10:12:47 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
10:12:47 INFO - 'PROCESSOR_LEVEL': '6',
10:12:47 INFO - 'PROCESSOR_REVISION': '1e05',
10:12:47 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
10:12:47 INFO - 'PROGRAMFILES': 'C:\\Program Files',
10:12:47 INFO - 'PROMPT': '$P$G',
10:12:47 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
10:12:47 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
10:12:47 INFO - 'PUBLIC': 'C:\\Users\\Public',
10:12:47 INFO - 'PWD': 'C:\\slave\\test',
10:12:47 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
10:12:47 INFO - 'SLAVEDIR': 'C:\\slave\\',
10:12:47 INFO - 'SYSTEMDRIVE': 'C:',
10:12:47 INFO - 'SYSTEMROOT': 'C:\\windows',
10:12:47 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
10:12:47 INFO - 'TEST1': 'testie',
10:12:47 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
10:12:47 INFO - 'USERDOMAIN': 'T-W732-IX-138',
10:12:47 INFO - 'USERNAME': 'cltbld',
10:12:47 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
10:12:47 INFO - 'WINDIR': 'C:\\windows',
10:12:47 INFO - 'WINDOWS_TRACING_FLAGS': '3',
10:12:47 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
10:12:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
10:12:48 INFO - Ignoring indexes: http://pypi.python.org/simple/
10:12:48 INFO - Downloading/unpacking pip>=1.5
10:12:52 INFO - Running setup.py egg_info for package pip
10:12:52 INFO - warning: no files found matching 'pip\cacert.pem'
10:12:52 INFO - warning: no files found matching '*.html' under directory 'docs'
10:12:52 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
10:12:52 INFO - no previously-included directories found matching 'docs\_build\_sources'
10:12:52 INFO - Installing collected packages: pip
10:12:52 INFO - Found existing installation: pip 0.8.2
10:12:52 INFO - Uninstalling pip:
10:12:52 INFO - Successfully uninstalled pip
10:12:52 INFO - Running setup.py install for pip
10:12:52 INFO - warning: no files found matching 'pip\cacert.pem'
10:12:52 INFO - warning: no files found matching '*.html' under directory 'docs'
10:12:52 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
10:12:52 INFO - no previously-included directories found matching 'docs\_build\_sources'
10:12:52 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts
10:12:52 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts
10:12:52 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts
10:12:52 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts
10:12:52 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts
10:12:52 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts
10:12:52 INFO - Successfully installed pip
10:12:52 INFO - Cleaning up...
10:12:52 INFO - Return code: 0
10:12:52 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv
10:12:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
10:12:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
10:12:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
10:12:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
10:12:52 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x01861640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01909E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0180B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01928E90>, '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 0x0123A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019DE520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-138', 'USERDOMAIN': 'T-W732-IX-138', '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-138', '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
10:12:52 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in C:\slave\test\build
10:12:52 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1
10:12:52 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
10:12:52 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
10:12:52 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
10:12:52 INFO - 'COMPUTERNAME': 'T-W732-IX-138',
10:12:52 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
10:12:52 INFO - 'CYGWINBASE': 'C:\\cygwin',
10:12:52 INFO - 'DCLOCATION': 'SCL3',
10:12:52 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
10:12:52 INFO - 'FP_NO_HOST_CHECK': 'NO',
10:12:52 INFO - 'HOMEDRIVE': 'C:',
10:12:52 INFO - 'HOMEPATH': '\\Users\\cltbld',
10:12:52 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
10:12:52 INFO - 'KTS_VERSION': '1.19c',
10:12:52 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
10:12:52 INFO - 'LOGONSERVER': '\\\\T-W732-IX-138',
10:12:52 INFO - 'MONDIR': 'C:\\Monitor_config\\',
10:12:52 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
10:12:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
10:12:52 INFO - 'MOZ_NO_REMOTE': '1',
10:12:52 INFO - 'NO_EM_RESTART': '1',
10:12:52 INFO - 'NUMBER_OF_PROCESSORS': '8',
10:12:52 INFO - 'OS': 'Windows_NT',
10:12:52 INFO - 'OURDRIVE': 'C:',
10:12:52 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
10:12:52 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
10:12:52 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
10:12:52 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
10:12:52 INFO - 'PROCESSOR_LEVEL': '6',
10:12:52 INFO - 'PROCESSOR_REVISION': '1e05',
10:12:52 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
10:12:52 INFO - 'PROGRAMFILES': 'C:\\Program Files',
10:12:52 INFO - 'PROMPT': '$P$G',
10:12:52 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
10:12:52 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
10:12:52 INFO - 'PUBLIC': 'C:\\Users\\Public',
10:12:52 INFO - 'PWD': 'C:\\slave\\test',
10:12:52 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
10:12:52 INFO - 'SLAVEDIR': 'C:\\slave\\',
10:12:52 INFO - 'SYSTEMDRIVE': 'C:',
10:12:52 INFO - 'SYSTEMROOT': 'C:\\windows',
10:12:52 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
10:12:52 INFO - 'TEST1': 'testie',
10:12:52 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
10:12:52 INFO - 'USERDOMAIN': 'T-W732-IX-138',
10:12:52 INFO - 'USERNAME': 'cltbld',
10:12:52 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
10:12:52 INFO - 'WINDIR': 'C:\\windows',
10:12:52 INFO - 'WINDOWS_TRACING_FLAGS': '3',
10:12:52 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
10:12:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
10:12:54 INFO - Ignoring indexes: https://pypi.python.org/simple/
10:12:54 INFO - Downloading/unpacking psutil>=3.1.1
10:12:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
10:12:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
10:12:55 INFO - Installing collected packages: psutil
10:12:55 INFO - Successfully installed psutil
10:12:55 INFO - Cleaning up...
10:12:55 INFO - Return code: 0
10:12:55 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv
10:12:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
10:12:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
10:12:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
10:12:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
10:12:55 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 0x01861640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01909E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0180B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01928E90>, '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 0x0123A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019DE520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-138', 'USERDOMAIN': 'T-W732-IX-138', '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-138', '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
10:12:55 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in C:\slave\test\build
10:12:55 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3
10:12:55 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
10:12:55 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
10:12:55 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
10:12:55 INFO - 'COMPUTERNAME': 'T-W732-IX-138',
10:12:55 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
10:12:55 INFO - 'CYGWINBASE': 'C:\\cygwin',
10:12:55 INFO - 'DCLOCATION': 'SCL3',
10:12:55 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
10:12:55 INFO - 'FP_NO_HOST_CHECK': 'NO',
10:12:55 INFO - 'HOMEDRIVE': 'C:',
10:12:55 INFO - 'HOMEPATH': '\\Users\\cltbld',
10:12:55 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
10:12:55 INFO - 'KTS_VERSION': '1.19c',
10:12:55 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
10:12:55 INFO - 'LOGONSERVER': '\\\\T-W732-IX-138',
10:12:55 INFO - 'MONDIR': 'C:\\Monitor_config\\',
10:12:55 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
10:12:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
10:12:55 INFO - 'MOZ_NO_REMOTE': '1',
10:12:55 INFO - 'NO_EM_RESTART': '1',
10:12:55 INFO - 'NUMBER_OF_PROCESSORS': '8',
10:12:55 INFO - 'OS': 'Windows_NT',
10:12:55 INFO - 'OURDRIVE': 'C:',
10:12:55 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
10:12:55 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
10:12:55 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
10:12:55 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
10:12:55 INFO - 'PROCESSOR_LEVEL': '6',
10:12:55 INFO - 'PROCESSOR_REVISION': '1e05',
10:12:55 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
10:12:55 INFO - 'PROGRAMFILES': 'C:\\Program Files',
10:12:55 INFO - 'PROMPT': '$P$G',
10:12:55 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
10:12:55 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
10:12:55 INFO - 'PUBLIC': 'C:\\Users\\Public',
10:12:55 INFO - 'PWD': 'C:\\slave\\test',
10:12:55 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
10:12:55 INFO - 'SLAVEDIR': 'C:\\slave\\',
10:12:55 INFO - 'SYSTEMDRIVE': 'C:',
10:12:55 INFO - 'SYSTEMROOT': 'C:\\windows',
10:12:55 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
10:12:55 INFO - 'TEST1': 'testie',
10:12:55 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
10:12:55 INFO - 'USERDOMAIN': 'T-W732-IX-138',
10:12:55 INFO - 'USERNAME': 'cltbld',
10:12:55 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
10:12:55 INFO - 'WINDIR': 'C:\\windows',
10:12:55 INFO - 'WINDOWS_TRACING_FLAGS': '3',
10:12:55 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
10:12:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
10:12:56 INFO - Ignoring indexes: https://pypi.python.org/simple/
10:12:56 INFO - Downloading/unpacking mozsystemmonitor==0.3
10:12:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
10:12:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
10:12:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz
10:12:57 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor
10:12:57 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)
10:12:57 INFO - Installing collected packages: mozsystemmonitor
10:12:57 INFO - Running setup.py install for mozsystemmonitor
10:12:57 INFO - Successfully installed mozsystemmonitor
10:12:57 INFO - Cleaning up...
10:12:57 INFO - Return code: 0
10:12:57 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv
10:12: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')]}
10:12:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
10:12: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')]}
10:12:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
10:12:57 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 0x01861640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01909E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0180B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01928E90>, '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 0x0123A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019DE520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-138', 'USERDOMAIN': 'T-W732-IX-138', '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-138', '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
10:12:57 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
10:12:57 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
10:12:57 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
10:12:57 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
10:12:57 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
10:12:57 INFO - 'COMPUTERNAME': 'T-W732-IX-138',
10:12:57 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
10:12:57 INFO - 'CYGWINBASE': 'C:\\cygwin',
10:12:57 INFO - 'DCLOCATION': 'SCL3',
10:12:57 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
10:12:57 INFO - 'FP_NO_HOST_CHECK': 'NO',
10:12:57 INFO - 'HOMEDRIVE': 'C:',
10:12:57 INFO - 'HOMEPATH': '\\Users\\cltbld',
10:12:57 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
10:12:57 INFO - 'KTS_VERSION': '1.19c',
10:12:57 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
10:12:57 INFO - 'LOGONSERVER': '\\\\T-W732-IX-138',
10:12:57 INFO - 'MONDIR': 'C:\\Monitor_config\\',
10:12:57 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
10:12:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
10:12:57 INFO - 'MOZ_NO_REMOTE': '1',
10:12:57 INFO - 'NO_EM_RESTART': '1',
10:12:57 INFO - 'NUMBER_OF_PROCESSORS': '8',
10:12:57 INFO - 'OS': 'Windows_NT',
10:12:57 INFO - 'OURDRIVE': 'C:',
10:12: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;',
10:12:57 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
10:12:57 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
10:12:57 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
10:12:57 INFO - 'PROCESSOR_LEVEL': '6',
10:12:57 INFO - 'PROCESSOR_REVISION': '1e05',
10:12:57 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
10:12:57 INFO - 'PROGRAMFILES': 'C:\\Program Files',
10:12:57 INFO - 'PROMPT': '$P$G',
10:12:57 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
10:12:57 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
10:12:57 INFO - 'PUBLIC': 'C:\\Users\\Public',
10:12:57 INFO - 'PWD': 'C:\\slave\\test',
10:12:57 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
10:12:57 INFO - 'SLAVEDIR': 'C:\\slave\\',
10:12:57 INFO - 'SYSTEMDRIVE': 'C:',
10:12:57 INFO - 'SYSTEMROOT': 'C:\\windows',
10:12:57 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
10:12:57 INFO - 'TEST1': 'testie',
10:12:57 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
10:12:57 INFO - 'USERDOMAIN': 'T-W732-IX-138',
10:12:57 INFO - 'USERNAME': 'cltbld',
10:12:57 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
10:12:57 INFO - 'WINDIR': 'C:\\windows',
10:12:57 INFO - 'WINDOWS_TRACING_FLAGS': '3',
10:12:57 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
10:12:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
10:12:58 INFO - Ignoring indexes: https://pypi.python.org/simple/
10:12:58 INFO - Downloading/unpacking jsonschema==2.5.1
10:12:58 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
10:12:58 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
10:12:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl
10:12:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1)
10:12:59 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
10:12:59 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
10:12:59 INFO - Downloading functools32-3.2.3-2.tar.gz
10:12:59 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32
10:12:59 INFO - warning: no files found matching '*.txt'
10:12:59 INFO - no previously-included directories found matching 'build'
10:12:59 INFO - no previously-included directories found matching 'dist'
10:12:59 INFO - no previously-included directories found matching '.git*'
10:12:59 INFO - Installing collected packages: jsonschema, functools32
10:12:59 INFO - Running setup.py install for functools32
10:12:59 INFO - warning: no files found matching '*.txt'
10:12:59 INFO - no previously-included directories found matching 'build'
10:12:59 INFO - no previously-included directories found matching 'dist'
10:12:59 INFO - no previously-included directories found matching '.git*'
10:12:59 INFO - Successfully installed jsonschema functools32
10:12:59 INFO - Cleaning up...
10:13:00 INFO - Return code: 0
10:13:00 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv
10:13:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
10:13:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
10:13:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
10:13:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
10:13:00 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x01861640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01909E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0180B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01928E90>, '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 0x0123A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019DE520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-138', 'USERDOMAIN': 'T-W732-IX-138', '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-138', '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
10:13:00 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in C:\slave\test\build
10:13:00 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2
10:13:00 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
10:13:00 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
10:13:00 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
10:13:00 INFO - 'COMPUTERNAME': 'T-W732-IX-138',
10:13:00 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
10:13:00 INFO - 'CYGWINBASE': 'C:\\cygwin',
10:13:00 INFO - 'DCLOCATION': 'SCL3',
10:13:00 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
10:13:00 INFO - 'FP_NO_HOST_CHECK': 'NO',
10:13:00 INFO - 'HOMEDRIVE': 'C:',
10:13:00 INFO - 'HOMEPATH': '\\Users\\cltbld',
10:13:00 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
10:13:00 INFO - 'KTS_VERSION': '1.19c',
10:13:00 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
10:13:00 INFO - 'LOGONSERVER': '\\\\T-W732-IX-138',
10:13:00 INFO - 'MONDIR': 'C:\\Monitor_config\\',
10:13:00 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
10:13:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
10:13:00 INFO - 'MOZ_NO_REMOTE': '1',
10:13:00 INFO - 'NO_EM_RESTART': '1',
10:13:00 INFO - 'NUMBER_OF_PROCESSORS': '8',
10:13:00 INFO - 'OS': 'Windows_NT',
10:13:00 INFO - 'OURDRIVE': 'C:',
10:13:00 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
10:13:00 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
10:13:00 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
10:13:00 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
10:13:00 INFO - 'PROCESSOR_LEVEL': '6',
10:13:00 INFO - 'PROCESSOR_REVISION': '1e05',
10:13:00 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
10:13:00 INFO - 'PROGRAMFILES': 'C:\\Program Files',
10:13:00 INFO - 'PROMPT': '$P$G',
10:13:00 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
10:13:00 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
10:13:00 INFO - 'PUBLIC': 'C:\\Users\\Public',
10:13:00 INFO - 'PWD': 'C:\\slave\\test',
10:13:00 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
10:13:00 INFO - 'SLAVEDIR': 'C:\\slave\\',
10:13:00 INFO - 'SYSTEMDRIVE': 'C:',
10:13:00 INFO - 'SYSTEMROOT': 'C:\\windows',
10:13:00 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
10:13:00 INFO - 'TEST1': 'testie',
10:13:00 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
10:13:00 INFO - 'USERDOMAIN': 'T-W732-IX-138',
10:13:00 INFO - 'USERNAME': 'cltbld',
10:13:00 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
10:13:00 INFO - 'WINDIR': 'C:\\windows',
10:13:00 INFO - 'WINDOWS_TRACING_FLAGS': '3',
10:13:00 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
10:13:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
10:13:00 INFO - Ignoring indexes: https://pypi.python.org/simple/
10:13:00 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages
10:13:00 INFO - Cleaning up...
10:13:00 INFO - Return code: 0
10:13:00 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv
10:13:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
10:13:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
10:13:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
10:13:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
10:13:00 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x01861640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01909E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0180B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01928E90>, '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 0x0123A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019DE520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-138', 'USERDOMAIN': 'T-W732-IX-138', '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-138', '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
10:13:00 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in C:\slave\test\build
10:13:00 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4
10:13:00 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
10:13:00 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
10:13:00 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
10:13:00 INFO - 'COMPUTERNAME': 'T-W732-IX-138',
10:13:00 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
10:13:00 INFO - 'CYGWINBASE': 'C:\\cygwin',
10:13:00 INFO - 'DCLOCATION': 'SCL3',
10:13:00 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
10:13:00 INFO - 'FP_NO_HOST_CHECK': 'NO',
10:13:00 INFO - 'HOMEDRIVE': 'C:',
10:13:00 INFO - 'HOMEPATH': '\\Users\\cltbld',
10:13:00 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
10:13:00 INFO - 'KTS_VERSION': '1.19c',
10:13:00 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
10:13:00 INFO - 'LOGONSERVER': '\\\\T-W732-IX-138',
10:13:00 INFO - 'MONDIR': 'C:\\Monitor_config\\',
10:13:00 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
10:13:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
10:13:00 INFO - 'MOZ_NO_REMOTE': '1',
10:13:00 INFO - 'NO_EM_RESTART': '1',
10:13:00 INFO - 'NUMBER_OF_PROCESSORS': '8',
10:13:00 INFO - 'OS': 'Windows_NT',
10:13:00 INFO - 'OURDRIVE': 'C:',
10:13:00 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
10:13:00 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
10:13:00 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
10:13:00 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
10:13:00 INFO - 'PROCESSOR_LEVEL': '6',
10:13:00 INFO - 'PROCESSOR_REVISION': '1e05',
10:13:00 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
10:13:00 INFO - 'PROGRAMFILES': 'C:\\Program Files',
10:13:00 INFO - 'PROMPT': '$P$G',
10:13:00 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
10:13:00 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
10:13:00 INFO - 'PUBLIC': 'C:\\Users\\Public',
10:13:00 INFO - 'PWD': 'C:\\slave\\test',
10:13:00 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
10:13:00 INFO - 'SLAVEDIR': 'C:\\slave\\',
10:13:00 INFO - 'SYSTEMDRIVE': 'C:',
10:13:00 INFO - 'SYSTEMROOT': 'C:\\windows',
10:13:00 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
10:13:00 INFO - 'TEST1': 'testie',
10:13:00 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
10:13:00 INFO - 'USERDOMAIN': 'T-W732-IX-138',
10:13:00 INFO - 'USERNAME': 'cltbld',
10:13:00 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
10:13:00 INFO - 'WINDIR': 'C:\\windows',
10:13:00 INFO - 'WINDOWS_TRACING_FLAGS': '3',
10:13:00 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
10:13:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
10:13:01 INFO - Ignoring indexes: https://pypi.python.org/simple/
10:13:01 INFO - Downloading/unpacking blobuploader==1.2.4
10:13:01 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
10:13:01 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
10:13:02 INFO - Downloading blobuploader-1.2.4.tar.gz
10:13:02 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader
10:13:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4)
10:13:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
10:13:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
10:13:02 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests
10:13:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4)
10:13:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
10:13:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
10:13:02 INFO - Downloading docopt-0.6.1.tar.gz
10:13:02 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt
10:13:02 INFO - Installing collected packages: blobuploader, requests, docopt
10:13:02 INFO - Running setup.py install for blobuploader
10:13:03 INFO - Running setup.py install for requests
10:13:04 INFO - Running setup.py install for docopt
10:13:04 INFO - Successfully installed blobuploader requests docopt
10:13:04 INFO - Cleaning up...
10:13:05 INFO - Return code: 0
10:13:05 INFO - Installing None into virtualenv C:\slave\test\build/venv
10:13: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')]}
10:13:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
10:13: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')]}
10:13:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
10:13:05 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 0x01861640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01909E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0180B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01928E90>, '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 0x0123A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019DE520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-138', 'USERDOMAIN': 'T-W732-IX-138', '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-138', '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
10:13:05 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
10:13:05 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
10:13:05 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
10:13:05 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
10:13:05 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
10:13:05 INFO - 'COMPUTERNAME': 'T-W732-IX-138',
10:13:05 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
10:13:05 INFO - 'CYGWINBASE': 'C:\\cygwin',
10:13:05 INFO - 'DCLOCATION': 'SCL3',
10:13:05 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
10:13:05 INFO - 'FP_NO_HOST_CHECK': 'NO',
10:13:05 INFO - 'HOMEDRIVE': 'C:',
10:13:05 INFO - 'HOMEPATH': '\\Users\\cltbld',
10:13:05 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
10:13:05 INFO - 'KTS_VERSION': '1.19c',
10:13:05 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
10:13:05 INFO - 'LOGONSERVER': '\\\\T-W732-IX-138',
10:13:05 INFO - 'MONDIR': 'C:\\Monitor_config\\',
10:13:05 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
10:13:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
10:13:05 INFO - 'MOZ_NO_REMOTE': '1',
10:13:05 INFO - 'NO_EM_RESTART': '1',
10:13:05 INFO - 'NUMBER_OF_PROCESSORS': '8',
10:13:05 INFO - 'OS': 'Windows_NT',
10:13:05 INFO - 'OURDRIVE': 'C:',
10:13:05 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
10:13:05 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
10:13:05 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
10:13:05 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
10:13:05 INFO - 'PROCESSOR_LEVEL': '6',
10:13:05 INFO - 'PROCESSOR_REVISION': '1e05',
10:13:05 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
10:13:05 INFO - 'PROGRAMFILES': 'C:\\Program Files',
10:13:05 INFO - 'PROMPT': '$P$G',
10:13:05 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
10:13:05 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
10:13:05 INFO - 'PUBLIC': 'C:\\Users\\Public',
10:13:05 INFO - 'PWD': 'C:\\slave\\test',
10:13:05 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
10:13:05 INFO - 'SLAVEDIR': 'C:\\slave\\',
10:13:05 INFO - 'SYSTEMDRIVE': 'C:',
10:13:05 INFO - 'SYSTEMROOT': 'C:\\windows',
10:13:05 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
10:13:05 INFO - 'TEST1': 'testie',
10:13:05 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
10:13:05 INFO - 'USERDOMAIN': 'T-W732-IX-138',
10:13:05 INFO - 'USERNAME': 'cltbld',
10:13:05 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
10:13:05 INFO - 'WINDIR': 'C:\\windows',
10:13:05 INFO - 'WINDOWS_TRACING_FLAGS': '3',
10:13:05 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
10:13:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
10:13:06 INFO - Ignoring indexes: https://pypi.python.org/simple/
10:13:06 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
10:13:06 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wqypg1-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
10:13:06 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
10:13:06 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zd5gq4-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
10:13:06 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
10:13:06 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-_8ybsg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
10:13:06 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
10:13:06 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-2a1qez-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
10:13:06 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
10:13:06 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qufjyp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
10:13:06 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
10:13:06 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-c_ytal-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
10:13:07 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
10:13:07 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-q8ygyi-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
10:13:07 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
10:13:07 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-f_plf9-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
10:13:07 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
10:13:07 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-tbdxyh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
10:13:07 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
10:13:07 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-7sd36n-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
10:13:07 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
10:13:07 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bfowuo-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
10:13:07 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
10:13:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-klyafk-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
10:13:08 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
10:13:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-l9rcvm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
10:13:08 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
10:13:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vq7zh3-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
10:13:08 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
10:13:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vp69jy-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
10:13:08 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
10:13:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-z_et6e-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
10:13:08 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
10:13:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-l3dq_u-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
10:13:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
10:13:08 INFO - Running setup.py install for manifestparser
10:13:09 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts
10:13:09 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts
10:13:09 INFO - Running setup.py install for mozcrash
10:13:10 INFO - Running setup.py install for mozdebug
10:13:10 INFO - Running setup.py install for mozdevice
10:13:10 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts
10:13:10 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts
10:13:10 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts
10:13:10 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts
10:13:11 INFO - Running setup.py install for mozfile
10:13:11 INFO - Running setup.py install for mozhttpd
10:13:11 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts
10:13:11 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts
10:13:11 INFO - Running setup.py install for mozinfo
10:13:12 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts
10:13:12 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts
10:13:12 INFO - Running setup.py install for mozInstall
10:13:12 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts
10:13:12 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts
10:13:12 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts
10:13:12 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts
10:13:12 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts
10:13:12 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts
10:13:12 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts
10:13:12 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts
10:13:12 INFO - Running setup.py install for mozleak
10:13:12 INFO - Running setup.py install for mozlog
10:13:13 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts
10:13:13 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts
10:13:13 INFO - Running setup.py install for moznetwork
10:13:13 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts
10:13:13 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts
10:13:14 INFO - Running setup.py install for mozprocess
10:13:14 INFO - Running setup.py install for mozprofile
10:13:14 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts
10:13:14 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts
10:13:14 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts
10:13:14 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts
10:13:14 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts
10:13:14 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts
10:13:15 INFO - Running setup.py install for mozrunner
10:13:15 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts
10:13:15 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts
10:13:16 INFO - Running setup.py install for mozscreenshot
10:13:16 INFO - Running setup.py install for moztest
10:13:16 INFO - Running setup.py install for mozversion
10:13:16 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts
10:13:16 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts
10:13:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion
10:13:17 INFO - Cleaning up...
10:13:17 INFO - Return code: 0
10:13:17 INFO - Installing None into virtualenv C:\slave\test\build/venv
10:13:17 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')]}
10:13:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
10:13:17 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')]}
10:13:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
10:13:17 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 0x01861640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01909E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0180B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01928E90>, '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 0x0123A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019DE520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-138', 'USERDOMAIN': 'T-W732-IX-138', '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-138', '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
10:13:17 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
10:13:17 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
10:13:17 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
10:13:17 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
10:13:17 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
10:13:17 INFO - 'COMPUTERNAME': 'T-W732-IX-138',
10:13:17 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
10:13:17 INFO - 'CYGWINBASE': 'C:\\cygwin',
10:13:17 INFO - 'DCLOCATION': 'SCL3',
10:13:17 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
10:13:17 INFO - 'FP_NO_HOST_CHECK': 'NO',
10:13:17 INFO - 'HOMEDRIVE': 'C:',
10:13:17 INFO - 'HOMEPATH': '\\Users\\cltbld',
10:13:17 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
10:13:17 INFO - 'KTS_VERSION': '1.19c',
10:13:17 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
10:13:17 INFO - 'LOGONSERVER': '\\\\T-W732-IX-138',
10:13:17 INFO - 'MONDIR': 'C:\\Monitor_config\\',
10:13:17 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
10:13:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
10:13:17 INFO - 'MOZ_NO_REMOTE': '1',
10:13:17 INFO - 'NO_EM_RESTART': '1',
10:13:17 INFO - 'NUMBER_OF_PROCESSORS': '8',
10:13:17 INFO - 'OS': 'Windows_NT',
10:13:17 INFO - 'OURDRIVE': 'C:',
10:13:17 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;',
10:13:17 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
10:13:17 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
10:13:17 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
10:13:17 INFO - 'PROCESSOR_LEVEL': '6',
10:13:17 INFO - 'PROCESSOR_REVISION': '1e05',
10:13:17 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
10:13:17 INFO - 'PROGRAMFILES': 'C:\\Program Files',
10:13:17 INFO - 'PROMPT': '$P$G',
10:13:17 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
10:13:17 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
10:13:17 INFO - 'PUBLIC': 'C:\\Users\\Public',
10:13:17 INFO - 'PWD': 'C:\\slave\\test',
10:13:17 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
10:13:17 INFO - 'SLAVEDIR': 'C:\\slave\\',
10:13:17 INFO - 'SYSTEMDRIVE': 'C:',
10:13:17 INFO - 'SYSTEMROOT': 'C:\\windows',
10:13:17 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
10:13:17 INFO - 'TEST1': 'testie',
10:13:17 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
10:13:17 INFO - 'USERDOMAIN': 'T-W732-IX-138',
10:13:17 INFO - 'USERNAME': 'cltbld',
10:13:17 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
10:13:17 INFO - 'WINDIR': 'C:\\windows',
10:13:17 INFO - 'WINDOWS_TRACING_FLAGS': '3',
10:13:17 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
10:13:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
10:13:17 INFO - Ignoring indexes: https://pypi.python.org/simple/
10:13:17 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
10:13:17 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-uiad_z-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
10:13:17 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))
10:13:17 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
10:13:17 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ccobzf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
10:13:17 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))
10:13:17 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
10:13:17 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hlaqiq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
10:13:18 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))
10:13:18 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
10:13:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pxxe9c-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
10:13:18 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))
10:13:18 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
10:13:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-5ihvb7-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
10:13:18 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))
10:13:18 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
10:13:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-84ws9f-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
10:13:18 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))
10:13:18 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
10:13:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-tyext_-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
10:13:18 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))
10:13:18 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
10:13:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-tett_j-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
10:13:18 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))
10:13:18 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
10:13:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-edcgda-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
10:13:18 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))
10:13:18 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
10:13:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-n_2u3j-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
10:13:19 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))
10:13:19 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
10:13:19 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pnqlwa-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
10:13:19 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))
10:13:19 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
10:13:19 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-6cd2rb-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
10:13:19 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))
10:13:19 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
10:13:19 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-2aoeu0-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
10:13:19 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))
10:13:19 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
10:13:19 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fmxawp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
10:13:19 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))
10:13:19 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
10:13:19 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-71xppi-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
10:13:19 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))
10:13:19 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
10:13:19 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kpzxpz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
10:13:19 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))
10:13:19 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
10:13:19 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dfz8gn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
10:13:20 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))
10:13:20 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))
10:13:20 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))
10:13:20 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))
10:13:20 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))
10:13:20 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))
10:13:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10))
10:13:20 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
10:13:20 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
10:13:21 INFO - Downloading blessings-1.6.tar.gz
10:13:21 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings
10:13:21 INFO - Installing collected packages: blessings
10:13:21 INFO - Running setup.py install for blessings
10:13:21 INFO - Successfully installed blessings
10:13:21 INFO - Cleaning up...
10:13:24 INFO - Return code: 0
10:13:24 INFO - Done creating virtualenv C:\slave\test\build/venv.
10:13:24 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
10:13:24 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
10:13:24 INFO - Reading from file tmpfile_stdout
10:13:24 INFO - Using _rmtree_windows ...
10:13:24 INFO - Using _rmtree_windows ...
10:13:24 INFO - Current package versions:
10:13:24 INFO - blessings == 1.6
10:13:24 INFO - blobuploader == 1.2.4
10:13:24 INFO - docopt == 0.6.1
10:13:24 INFO - functools32 == 3.2.3-2
10:13:24 INFO - jsonschema == 2.5.1
10:13:24 INFO - manifestparser == 1.1
10:13:24 INFO - mozInstall == 1.12
10:13:24 INFO - mozcrash == 0.17
10:13:24 INFO - mozdebug == 0.1
10:13:24 INFO - mozdevice == 0.48
10:13:24 INFO - mozfile == 1.2
10:13:24 INFO - mozhttpd == 0.7
10:13:24 INFO - mozinfo == 0.9
10:13:24 INFO - mozleak == 0.1
10:13:24 INFO - mozlog == 3.4
10:13:24 INFO - moznetwork == 0.27
10:13:24 INFO - mozprocess == 0.23
10:13:24 INFO - mozprofile == 0.28
10:13:24 INFO - mozrunner == 6.12
10:13:24 INFO - mozscreenshot == 0.1
10:13:24 INFO - mozsystemmonitor == 0.3
10:13:24 INFO - moztest == 0.7
10:13:24 INFO - mozversion == 1.4
10:13:24 INFO - psutil == 3.1.1
10:13:24 INFO - requests == 1.2.3
10:13:24 INFO - Installing None into virtualenv C:\slave\test\build/venv
10:13:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
10:13:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
10:13:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
10:13:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
10:13:24 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 0x01861640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01909E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0180B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01928E90>, '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 0x0123A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019DE520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-138', 'USERDOMAIN': 'T-W732-IX-138', '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-138', '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
10:13:24 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
10:13:24 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
10:13:24 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
10:13:24 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
10:13:24 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
10:13:24 INFO - 'COMPUTERNAME': 'T-W732-IX-138',
10:13:24 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
10:13:24 INFO - 'CYGWINBASE': 'C:\\cygwin',
10:13:24 INFO - 'DCLOCATION': 'SCL3',
10:13:24 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
10:13:24 INFO - 'FP_NO_HOST_CHECK': 'NO',
10:13:24 INFO - 'HOMEDRIVE': 'C:',
10:13:24 INFO - 'HOMEPATH': '\\Users\\cltbld',
10:13:24 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
10:13:24 INFO - 'KTS_VERSION': '1.19c',
10:13:24 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
10:13:24 INFO - 'LOGONSERVER': '\\\\T-W732-IX-138',
10:13:24 INFO - 'MONDIR': 'C:\\Monitor_config\\',
10:13:24 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
10:13:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
10:13:24 INFO - 'MOZ_NO_REMOTE': '1',
10:13:24 INFO - 'NO_EM_RESTART': '1',
10:13:24 INFO - 'NUMBER_OF_PROCESSORS': '8',
10:13:24 INFO - 'OS': 'Windows_NT',
10:13:24 INFO - 'OURDRIVE': 'C:',
10:13:24 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
10:13:24 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
10:13:24 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
10:13:24 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
10:13:24 INFO - 'PROCESSOR_LEVEL': '6',
10:13:24 INFO - 'PROCESSOR_REVISION': '1e05',
10:13:24 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
10:13:24 INFO - 'PROGRAMFILES': 'C:\\Program Files',
10:13:24 INFO - 'PROMPT': '$P$G',
10:13:24 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
10:13:24 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
10:13:24 INFO - 'PUBLIC': 'C:\\Users\\Public',
10:13:24 INFO - 'PWD': 'C:\\slave\\test',
10:13:24 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
10:13:24 INFO - 'SLAVEDIR': 'C:\\slave\\',
10:13:24 INFO - 'SYSTEMDRIVE': 'C:',
10:13:24 INFO - 'SYSTEMROOT': 'C:\\windows',
10:13:24 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
10:13:24 INFO - 'TEST1': 'testie',
10:13:24 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
10:13:24 INFO - 'USERDOMAIN': 'T-W732-IX-138',
10:13:24 INFO - 'USERNAME': 'cltbld',
10:13:24 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
10:13:24 INFO - 'WINDIR': 'C:\\windows',
10:13:24 INFO - 'WINDOWS_TRACING_FLAGS': '3',
10:13:24 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
10:13:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
10:13:25 INFO - Ignoring indexes: https://pypi.python.org/simple/
10:13:25 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))
10:13:25 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))
10:13:25 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))
10:13:25 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))
10:13:25 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))
10:13:25 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))
10:13:25 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))
10:13:25 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))
10:13:25 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))
10:13:25 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))
10:13:25 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))
10:13:25 INFO - Cleaning up...
10:13:25 INFO - Return code: 0
10:13:25 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv
10:13: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')]}
10:13:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
10:13: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')]}
10:13:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
10:13: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01861640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01909E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0180B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01928E90>, '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 0x0123A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019DE520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-138', 'USERDOMAIN': 'T-W732-IX-138', '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-138', '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
10:13: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', 'jsonschema'] in C:\slave\test\build
10:13: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 jsonschema
10:13:25 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
10:13:25 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
10:13:25 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
10:13:25 INFO - 'COMPUTERNAME': 'T-W732-IX-138',
10:13:25 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
10:13:25 INFO - 'CYGWINBASE': 'C:\\cygwin',
10:13:25 INFO - 'DCLOCATION': 'SCL3',
10:13:25 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
10:13:25 INFO - 'FP_NO_HOST_CHECK': 'NO',
10:13:25 INFO - 'HOMEDRIVE': 'C:',
10:13:25 INFO - 'HOMEPATH': '\\Users\\cltbld',
10:13:25 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
10:13:25 INFO - 'KTS_VERSION': '1.19c',
10:13:25 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
10:13:25 INFO - 'LOGONSERVER': '\\\\T-W732-IX-138',
10:13:25 INFO - 'MONDIR': 'C:\\Monitor_config\\',
10:13:25 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
10:13:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
10:13:25 INFO - 'MOZ_NO_REMOTE': '1',
10:13:25 INFO - 'NO_EM_RESTART': '1',
10:13:25 INFO - 'NUMBER_OF_PROCESSORS': '8',
10:13:25 INFO - 'OS': 'Windows_NT',
10:13:25 INFO - 'OURDRIVE': 'C:',
10:13: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;',
10:13:25 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
10:13:25 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
10:13:25 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
10:13:25 INFO - 'PROCESSOR_LEVEL': '6',
10:13:25 INFO - 'PROCESSOR_REVISION': '1e05',
10:13:25 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
10:13:25 INFO - 'PROGRAMFILES': 'C:\\Program Files',
10:13:25 INFO - 'PROMPT': '$P$G',
10:13:25 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
10:13:25 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
10:13:25 INFO - 'PUBLIC': 'C:\\Users\\Public',
10:13:25 INFO - 'PWD': 'C:\\slave\\test',
10:13:25 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
10:13:25 INFO - 'SLAVEDIR': 'C:\\slave\\',
10:13:25 INFO - 'SYSTEMDRIVE': 'C:',
10:13:25 INFO - 'SYSTEMROOT': 'C:\\windows',
10:13:25 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
10:13:25 INFO - 'TEST1': 'testie',
10:13:25 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
10:13:25 INFO - 'USERDOMAIN': 'T-W732-IX-138',
10:13:25 INFO - 'USERNAME': 'cltbld',
10:13:25 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
10:13:25 INFO - 'WINDIR': 'C:\\windows',
10:13:25 INFO - 'WINDOWS_TRACING_FLAGS': '3',
10:13:25 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
10:13:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
10:13:25 INFO - Ignoring indexes: https://pypi.python.org/simple/
10:13:25 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages
10:13:25 INFO - Cleaning up...
10:13:25 INFO - Return code: 0
10:13:25 INFO - Running post-action listener: _resource_record_post_action
10:13:25 INFO - Running post-action listener: _start_resource_monitoring
10:13:25 INFO - Starting resource monitoring.
10:13:25 INFO - [mozharness: 2016-09-25 17:13:25.760000Z] Finished create-virtualenv step (success)
10:13:25 INFO - [mozharness: 2016-09-25 17:13:25.760000Z] Running install step.
10:13:25 INFO - Running pre-action listener: _resource_record_pre_action
10:13:25 INFO - Running main action method: install
10:13:25 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
10:13:25 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
10:13:26 INFO - Reading from file tmpfile_stdout
10:13:26 INFO - Using _rmtree_windows ...
10:13:26 INFO - Using _rmtree_windows ...
10:13:26 INFO - Detecting whether we're running mozinstall >=1.0...
10:13:26 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h']
10:13:26 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h
10:13:26 INFO - Reading from file tmpfile_stdout
10:13:26 INFO - Output received:
10:13:26 INFO - Usage: mozinstall-script.py [options] installer
10:13:26 INFO - Options:
10:13:26 INFO - -h, --help show this help message and exit
10:13:26 INFO - -d DEST, --destination=DEST
10:13:26 INFO - Directory to install application into. [default:
10:13:26 INFO - "C:\slave\test"]
10:13:26 INFO - --app=APP Application being installed. [default: firefox]
10:13:26 INFO - Using _rmtree_windows ...
10:13:26 INFO - Using _rmtree_windows ...
10:13:26 INFO - mkdir: C:\slave\test\build\application
10:13:26 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']
10:13:26 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
10:13:28 INFO - Reading from file tmpfile_stdout
10:13:28 INFO - Output received:
10:13:28 INFO - C:\slave\test\build\application\firefox\firefox.exe
10:13:28 INFO - Using _rmtree_windows ...
10:13:28 INFO - Using _rmtree_windows ...
10:13:28 INFO - Running post-action listener: _resource_record_post_action
10:13:28 INFO - [mozharness: 2016-09-25 17:13:28.611000Z] Finished install step (success)
10:13:28 INFO - [mozharness: 2016-09-25 17:13:28.611000Z] Running run-tests step.
10:13:28 INFO - Running pre-action listener: _resource_record_pre_action
10:13:28 INFO - Running main action method: run_tests
10:13:28 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version']
10:13:28 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version
10:13:28 INFO - Python 2.7.3
10:13:28 INFO - Return code: 0
10:13:28 INFO - grabbing minidump binary from tooltool
10:13:28 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')]}
10:13:28 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 0x01928E90>, '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 0x0123A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019DE520>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1
10:13:28 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
10:13:28 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
10:13:28 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
10:13:28 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'...
10:13:29 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmpigemnh
10:13:29 INFO - INFO - File integrity verified, renaming tmpigemnh to win32-minidump_stackwalk.exe
10:13:29 INFO - Return code: 0
10:13:29 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755
10:13:29 INFO - mkdir: C:\slave\test\build\blobber_upload_dir
10:13:29 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir
10:13:29 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe
10:13:29 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir
10:13:29 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/HcsnZxEbQPiBMFYuFdrp8A/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-138', '--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
10:13:29 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/HcsnZxEbQPiBMFYuFdrp8A/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-138 --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
10:13:29 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
10:13:29 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
10:13:29 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
10:13:29 INFO - 'COMPUTERNAME': 'T-W732-IX-138',
10:13:29 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
10:13:29 INFO - 'CYGWINBASE': 'C:\\cygwin',
10:13:29 INFO - 'DCLOCATION': 'SCL3',
10:13:29 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
10:13:29 INFO - 'FP_NO_HOST_CHECK': 'NO',
10:13:29 INFO - 'HOMEDRIVE': 'C:',
10:13:29 INFO - 'HOMEPATH': '\\Users\\cltbld',
10:13:29 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
10:13:29 INFO - 'KTS_VERSION': '1.19c',
10:13:29 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
10:13:29 INFO - 'LOGONSERVER': '\\\\T-W732-IX-138',
10:13:29 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir',
10:13:29 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe',
10:13:29 INFO - 'MONDIR': 'C:\\Monitor_config\\',
10:13:29 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
10:13:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
10:13:29 INFO - 'MOZ_NO_REMOTE': '1',
10:13:29 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir',
10:13:29 INFO - 'NO_EM_RESTART': '1',
10:13:29 INFO - 'NUMBER_OF_PROCESSORS': '8',
10:13:29 INFO - 'OS': 'Windows_NT',
10:13:29 INFO - 'OURDRIVE': 'C:',
10:13: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;',
10:13:29 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
10:13:29 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
10:13:29 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
10:13:29 INFO - 'PROCESSOR_LEVEL': '6',
10:13:29 INFO - 'PROCESSOR_REVISION': '1e05',
10:13:29 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
10:13:29 INFO - 'PROGRAMFILES': 'C:\\Program Files',
10:13:29 INFO - 'PROMPT': '$P$G',
10:13:29 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
10:13:29 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
10:13:29 INFO - 'PUBLIC': 'C:\\Users\\Public',
10:13:29 INFO - 'PWD': 'C:\\slave\\test',
10:13:29 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos',
10:13:29 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
10:13:29 INFO - 'SLAVEDIR': 'C:\\slave\\',
10:13:29 INFO - 'SYSTEMDRIVE': 'C:',
10:13:29 INFO - 'SYSTEMROOT': 'C:\\windows',
10:13:29 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
10:13:29 INFO - 'TEST1': 'testie',
10:13:29 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
10:13:29 INFO - 'USERDOMAIN': 'T-W732-IX-138',
10:13:29 INFO - 'USERNAME': 'cltbld',
10:13:29 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
10:13:29 INFO - 'WINDIR': 'C:\\windows',
10:13:29 INFO - 'WINDOWS_TRACING_FLAGS': '3',
10:13:29 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
10:13:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
10:13:29 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/HcsnZxEbQPiBMFYuFdrp8A/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-138', '--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
10:13:30 INFO - mozversion application_buildid: 20160925063030
10:13:30 INFO - mozversion application_changeset: 5011b0476532fbc5b36a8d75e4618c7d040526cd
10:13:30 INFO - mozversion application_display_name: Nightly
10:13:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
10:13:30 INFO - mozversion application_name: Firefox
10:13:30 INFO - mozversion application_remotingname: firefox
10:13:30 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland
10:13:30 INFO - mozversion application_vendor: Mozilla
10:13:30 INFO - mozversion application_version: 52.0a1
10:13:30 INFO - mozversion platform_buildid: 20160925063030
10:13:30 INFO - mozversion platform_changeset: 5011b0476532fbc5b36a8d75e4618c7d040526cd
10:13:30 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland
10:13:30 INFO - mozversion platform_version: 52.0a1
10:13:30 INFO - using testdate: 1474823610
10:13:30 INFO - actual date: 1474823610
10:13:30 INFO - starting webserver on 'localhost:50002'
10:13:30 INFO - SUITE-START | Running 2 tests
10:13:30 INFO - TEST-START | dromaeo_css
10:13:30 INFO - operating with platform_type : w7_
10:13:30 INFO - Initialising browser for dromaeo_css test...
10:13:30 INFO - TEST-INFO | started process 1492 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpn84l6y\profile http://localhost:50002/getInfo.html)
10:13:36 INFO - PROCESS | 1492 | __metrics Screen width/height:1600/1200
10:13:36 INFO - PROCESS | 1492 | colorDepth:24
10:13:36 INFO - PROCESS | 1492 | Browser inner width/height: 1010/674
10:13:36 INFO - PROCESS | 1492 | __metrics
10:13:37 INFO - PROCESS | 1492 | 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"
10:13:37 INFO - PROCESS | 1492 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
10:13:38 INFO - TEST-INFO | 1492: exit 0
10:13:38 INFO - Browser initialized.
10:13:38 INFO - Running cycle 1/1 for dromaeo_css test...
10:13:38 INFO - TEST-INFO | started process 3956 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpn84l6y\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1)
10:13:39 INFO - PROCESS | 3956 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0
10:13:50 INFO - PROCESS | 3956 | RSS: Main: 124837888
10:13:50 INFO - PROCESS | 3956 |
10:14:40 INFO - PROCESS | 3956 | 1474823680914 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]
10:16:49 INFO - PROCESS | 3956 | Cycle 1(1): loaded http://localhost:50002/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50002/tests/dromaeo/cssquery-ext.html)
10:16:51 INFO - PROCESS | 3956 | RSS: Main: 287158272
10:16:51 INFO - PROCESS | 3956 |
10:19:53 INFO - PROCESS | 3956 | Cycle 1(1): loaded http://localhost:50002/tests/dromaeo/cssquery-ext.html (next: http://localhost:50002/tests/dromaeo/cssquery-jquery.html)
10:19:54 INFO - PROCESS | 3956 | RSS: Main: 196616192
10:19:54 INFO - PROCESS | 3956 |
10:22:56 INFO - PROCESS | 3956 | Cycle 1(1): loaded http://localhost:50002/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50002/tests/dromaeo/cssquery-mootools.html)
10:22:56 INFO - PROCESS | 3956 | RSS: Main: 158699520
10:22:56 INFO - PROCESS | 3956 |
10:25:58 INFO - PROCESS | 3956 | Cycle 1(1): loaded http://localhost:50002/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50002/tests/dromaeo/cssquery-prototype.html)
10:25:59 INFO - PROCESS | 3956 | RSS: Main: 143884288
10:25:59 INFO - PROCESS | 3956 |
10:29:02 INFO - PROCESS | 3956 | Cycle 1(1): loaded http://localhost:50002/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50002/tests/dromaeo/cssquery-yui.html)
10:29:02 INFO - PROCESS | 3956 | RSS: Main: 130736128
10:29:02 INFO - PROCESS | 3956 |
10:32:05 INFO - PROCESS | 3956 | Cycle 1(1): loaded http://localhost:50002/tests/dromaeo/cssquery-yui.html (next: http://localhost:50002/tests/dromaeo/cssquery-dojo.html)
10:32:05 INFO - PROCESS | 3956 | RSS: Main: 276570112
10:32:05 INFO - PROCESS | 3956 |
10:32:05 INFO - PROCESS | 3956 | __start_tp_report
10:32:05 INFO - PROCESS | 3956 | _x_x_mozilla_page_load
10:32:05 INFO - PROCESS | 3956 | _x_x_mozilla_page_load_details
10:32:05 INFO - PROCESS | 3956 | |i|pagename|runs|
10:32:05 INFO - PROCESS | 3956 | |0;dojo.html;2706.2693072870325;2727.290904364865;2747.6015977683237;2749.20273120795;2753.4493101379717;4695.67130300879;4713.175194340989;4796.592289655375;4797.952020479798;4817.205161148407;4694.014257006024;4718.174319494092;4728.314394412808;4752.981856521108;4835.9274610880775;4189.287821070426;4205.05386288085;4210.7473551587;4211.073563815956;4226.492820861486;4105.137921036586;4162.771047592381;4212.136512015045;4247.787610619472;4248.617624413802;4106.589341065885;4125.484314460692;4200.3069493533685;4206.453161089054;4214.262504061792;3937.704672149586;4124.1957818225455;4173.582641735832;4187.078842654611;4192.601702838226;3886.9805650971566;3956.188981258864;4078.1028173801715;4113.85601503946;4152.937705934413;4601.493835678073;4659.394278743742;4680.508546602592;4739.763011849428;4744.786484608167;4929.556339929423;4943.431505376856;5037.899242015139;5040.193569028937;5051.823186188502;4941.950580494185;4976.925346119811;4991.575716064139;5003.824866129705;5056.974715126401;4634.189016922025;4650.674452788307;4732.432108147044;4738.24188129901;4747.7626118693925;4930.9013819723405;5073.213651883964;5142.280080788692;5163.535281824654;5171.3535808024;4560.315952607136;4826;4828.396450443694;4883.291922671193;4915.680480768739;4852.490488498727;4984.177610694195;5006.824761133343;5019.949800501949;5079.085764562415;4896.902061958741;4908.852734417973;4933.0627180836245;5037;5039.899202016012;4883.096627123993;4907.435644900882;4922.876928076755;5012.8496145115705;5044.621653376012;3329.8335083245743;3337.4159522083537;3343.4316166251797;3366.1584603849037;3368.4778859276853;3086.058752080637;3090.5209692497697;3100.627924649011;3100.8139511629374;3102.2399512119223;4760.047990401864;4766.142094422968;4831.130396528588;4861.562459378673;4878.975605121952;4892.3884514435695;4956.776945037482;4986.650934434628;4988.301637770715;5037.219231019198;4812.735299558488;4860.73265970375;4866.270059491034;4872.78072486739;4878.707277563287;4044.373122166069;4119.567445418248;4164.583541645811;4191.517975432804;4195;5178.585713142939;5206.921896171561;5241.371035475768;5341.3857406398765;5368.141097424394;4950.579200767975;4963.404391473046;4974.228994505857;4983.626228032839;5039.387396033234;5290.153575427913;5300.045991721528;5307.734613269398;5406.78372865081;5410.621256512164;4949.455559888337;4967.577755890645;5024.296598476143;5058.038972595195;5062.31658726082;4952.653314267966;4965.379327584052;4980.32765576657;5037.722925239147;5119.46245644202;4840.661153719206;4848.612111031039;4871.707697538159;4890.437599675941;4897.926531101965;4856.635752318661;4879.072976134523;4881.633877459134;4884.316195732529;4889.731064791328;4739.957209414061;4794.2089555223;4799.808007679654;4802.063597598435;4808.966072294334;4881.609471242221;4903.5586797188425;4908.852734417973;4911.607071434205;4919.827806026772;4877.585405240452;4882.95117048825;4891.217405215149;4892.168331383602;4896.020795840775
10:32:05 INFO - PROCESS | 3956 | |1;ext.html;527.976241069152;552.6766841397783;556.1074475466876;565.2510423688614;582.9506887602315;38914.610853891485;40399.59600403995;40405.19189616206;40510.59489405105;42629.57370426295;41832.163356732846;42258.577414225845;42303.57696423035;42308;42344.36483452743;8329.50022998619;8363.623636936352;8425.283850872684;8509.404341696083;8521.573921303941;8278.544680042596;8282.213189746964;8311.085780564134;8429.283510901581;8433.662653493853;8469.830603387929;8488.83022339553;8496.192861678162;8498.575071246443;8505.489670619783;8311.1688831117;8366.707165249218;8369.079401265855;8419.95790021052;8426.199511046441;8287.254147126787;8378.455400398954;8451.408401411903;8452.450590711645;8456.674414536052;38527.8073609633;38837;39005.60994390048;39056.80471597652;39066;3200.7439404847555;3287.6383597804224;3475.4091804393315;3523.1544429336773;3798.4492248623797;29706.405871882656;30619.846900765577;30954.380912381625;31844;31893.607979401404;3386.2888793353427;3631.509746184258;3698.833383782504;3720.1815600567834;3827.885163445101;241981;330342.9999999976;330844;330983;332409;53806.73096634492;54290.728546357015;54305.45694543083;54332;54412.455875441534;55747.16379254314;55811.72094139585;57583.712081440164;58310.416895831346;58458.123128153115;13179.406926688322;13546.45814167442;13552.661183470493;13585;13607.455701772016;10045.045720656526;10107.545160467796;10128;10166.898331016595;10209;18001.369952051617;18096.18567164481;18152.818471815375;18228.54428639268;18285.177167027516;7193.712251509881;7281.890771638429;7283.271672832674;7322.707091716272;7339.596322202224;816.0411516468245;850.6002178975872;868.8957325121025;926.9165775080276;995.9053889880449;28147.71852281451;28233.71766282311;28966.710332896822;29403.264918377212;29460;941.3739862991034;945.6784693204206;959.318883592643;971.4365667912593;1065.1425602389995;15425.768613470807;15752.448664296695;15807.36770529166;15889.205539722967;16096.356145754038;442697;447207;447959;449114;451262;50049.74975125174;50169.49830501648;50336;50368.24447633288;50491.747541262055;1129.1869853705318;1168.2698313554029;1175.5415387998687;1177.711460692136;1181.6927598824195;28008.579871302354;29039.98360057388;29259.268518287212;29275.85362073176;29326;43907;44289;44354.77822610866;44440.888977775816;44557.55442445534;404877;425165;426275;426975;429274;22465.32604021882;23333.41666458352;23351.182708605116;23377.532449351256;23546.52906941886;42101.57898421099;42505.57494425016;42602.57397425986;42615.147697045264;42687.14625707531;36184.276314473034;36393.63606363902;36922.261554768214;36931.26137477289;37620.05949851275;36395.27209455849;36872.26255474929;37226;37553;37919;34884.82557587297;34931;35222.11944701403;35339.823300883334;35592.46611300781;32391.676083238865;32425.837870810494;32607.18482037873;32760.180995475304;33535.496967545994;24754.628680569447;25495.6175657369;25607.847646855564;26066.478670426866;26409
10:32:05 INFO - PROCESS | 3956 | |2;jquery.html;279.5359702893198;299.95914177803024;303.97872148949574;311.2452303164825;316.96830316968305;12836.871631283697;17517.912410437944;18005.81994180056;18153.364632237874;18754.531136721587;17434;17707.29170833165;17781.28874845008;17869.64260714785;17970.460886173434;5277.947220527793;5380.946190538103;5416.295881535395;5472.097103977839;5482.643628164156;5230.424653288135;5407.242985981946;5423.891522169574;5432.674039557639;5440.945590544084;5354.437784032679;5369.785208591652;5374.8925021499745;5376.1398176291805;5376.596755243353;5208;5241.24002019705;5300.549453296474;5337.759800808973;5365.248865158881;4510.736993641753;4533.206688829475;4635.980084381431;4639.234526303156;4642;21546.676799848014;21984.230551930767;22172.778272217234;22182.778172218394;22216.333509994725;2171.1532502324108;2171.924897175892;2175.3473957812753;2392.294273189413;2815.746582807557;14095.22476263805;14243.572692819233;14539.200343981076;14819.036762610504;14902.254887255594;1672.180631490573;1721.6642754662846;1722.2336060453104;1775.325376356989;1784.9286028558843;82914.58542707308;83240.58379708063;84625.57687211587;85456.99999999937;86931.99999999937;8567.443116197428;8603.870941935877;8795.516246606498;8856.468611883307;9040.909590904006;10173.898261017295;10559.260851740304;10692.358458492516;11292.774144517063;11299.265547739371;7105.46708996817;7130.215676275606;7133.857322853514;7216.927830721731;7267.491275610657;7372.852542949218;7398.075240594926;7474.364679002237;7485.139208991036;7498;5513.917291240555;5680.261565996477;5710.486056254874;5776.797812076557;5899.734511946973;1500.8724258438156;1527.816662000567;1528.717187320327;1537.2006556590734;1548.4812587782994;817.2685446525327;885.3758100539104;920.0318281281135;930.7533503621546;971.8639390485338;18574.907125464288;19022.42932711993;19073.332433364765;19276.710849337273;19648.21407143698;893.2273583350355;924.8057907839429;925.3661242049218;932.5337331334333;942.4910548303839;10560.947195263974;11024.779504409868;11038.779224415466;11054.613088542023;11075.224734268524;395922;400969;403283;403987;406776;53957.73021134948;82881;84674.57662711648;85491.14508854832;85639.57180214059;846.6824940647257;847.3306088190384;860.5052095045374;873.4148120759106;882.0385779500375;16993.150342483073;17426.390076347776;17465.126743662513;17468.388606398716;17679.823201767816;42094.78952605218;42147.677848322994;42439.7878010608;42925.78537107295;43016;60906;60935.69532152488;62112.37887621247;67739;68506.65746671235;7371.963140184264;7460.253974602496;7495.550266983998;7535.886961695469;7585.355244804033;9572.617095316109;13944.58166255014;13964.162150271017;14000.929995349958;14115.364808583434;11404.144689148181;11482.82775758381;11559.133065019989;11593.24643898144;11752.412379380825;11081.5567377304;11257.774844503228;11385.601503947322;11591.594294199662;11595.420228988349;11703.122265829927;11743.588974385855;11756.58851940178;11970.341631210018;12096;7281.817954551178;7505.136909255505;7582.431317651088;7642.656080476503;7684.961575192088;10346.482675866026;11450.541978320774;11563.248388854698;11750.412479376168;11756.529738810352
10:32:05 INFO - PROCESS | 3956 | |3;mootools.html;1125.2517076144363;1228.1464382254335;1238.6284114765574;1274.5666473399042;1547.2573164880864;6299;6974.895376569356;7237.27627237276;7277.781666550012;7456.179820219785;7258;7330.376917961979;7333.046703928482;7336.633168341588;7343.52267102638;1888.3673969220329;1935.419374187745;1942.7377304063982;1945.221911235503;1945.9513512162239;1895.4882181810972;1923.7595300587427;1926.2776458827939;1930.4112245765036;1968.4193163016873;1880.0881572437356;1921.0970843703437;1941.1944043222115;1944.9124789384512;1949.7172909928051;1826.1873466307497;1869.8780731561092;1871.728599353086;1872.9719054214197;1873.868829181951;1823.1977929710886;1860.906954652262;1866.160227897438;1870.2332043862086;1872.6722823505968;7009.264027277113;7054.365107140331;7148.49960502765;7164.247753985808;7175.210726820045;4228.534861165269;4356.106998065389;4439.400680908101;4462.776861156929;4466.9106617867465;2778.569321755131;2861.2703760541135;2901.9854900725572;2902.071337172106;2915.7813164012573;773.1263672050546;796.3947399976003;802.2659266770897;807.4953154278576;808.975730728079;9201.493917834516;9263.814723705556;9278.35051546392;9279.304052196112;9284.164425201696;3555.822208889545;3571.0715214044535;3630.7640003399692;3646.3254297954963;3647.124690074363;7364.116306043202;7380.815479612946;7382.040334756447;7384.667689953965;7389.630518474055;4265.1469706058915;4285.807138678704;4307.289297265916;4321.481422229353;4321.524632290446;3554.5201397811484;3665.248624032067;4116.094459218967;4117.794110294473;4120.2583534964;2939.9706002940125;3053.26721586822;3085.3674996625637;3093.2885436349766;3109.7667674924473;903.0969030969031;904;911.7857303533797;927.1377618814568;927.7077720518015;796.4703472191044;810.2140923304386;811.8903947967067;814.3973459639824;850.3707256630049;4866.926996095061;4867.513248675175;4949.158643030693;4994.350734404504;5069.721165335868;793.3534169651715;802.2739420824164;818.4598165210932;849.8087930215776;860.7116615933733;3256.234784825564;3388.3223355329033;3414.2830005698584;3415.419378705626;3478.4260597001717;8453.450525715809;8712.825743485222;8742.213200811957;8790.648374064966;8819.691310804092;8526;8596.527191004554;8613.397062205586;8702.825943481095;8750.91249087501;6781.559198652072;6831.726730930707;6868.484863635247;6890.10428644273;6892.1729392472225;4762.595179409789;4772.785224664829;4828.179209534317;4832.420109586872;4893.266010098514;7997.240262175084;8108.79728006781;8131.146229645802;8211.384146189179;8287.834243315221;8007.278435136241;8157.79605509867;8204.58977051157;8225.629846656795;8278.875816862632;5779.133130030529;5786.9131963019745;5851.356350801323;5862;5883.499902508334;6236.095766113979;6438.484921206386;6804.4896632753735;6998.440124789903;7003.789886303419;6145.13968044483;6308.558400911892;6327.936720632735;6354.904676429765;6408.839779005562;5759.71201439918;6147.72335244924;6319.9052014218905;6342.809715708536;6365.681715914278;5735.684537350319;5861.677607731615;5873.618214816023;5876.911846322223;5893.6463812171405;4308.633766129914;4360.52034276223;4387.298032314942;4391.033972526039;4478.641708663234;4133.545310015836;4202.390653355185;4220.240356735817;4403.603675669206;4528.275475923836
10:32:05 INFO - PROCESS | 3956 | |4;prototype.html;235.39855668766296;236.25461668436085;236.87682405149323;241.7993065755423;247.46670924158445;5139.25480805283;5147.150720131174;5182.248573956774;5186.965625109311;5190.377154741427;4878.000009997942;4923.704577725339;5012.72430016349;5054.469280725517;5091.032703786287;3667.449882517617;3698.7225958053255;3701.9079371585312;3703.574088979768;3716.479692843004;3680.5215322008103;3690.225052738928;3693.150575367667;3707;3710.18375957289;3535.8585656573837;3593;3625.6193099724546;3686.760360576554;3689.0039689283876;3623.800690961996;3673.742838001341;3676.8345424455965;3677.2461645362637;3685.318216129998;3165.8100513969007;3170.4927211646027;3171.619405671311;3173.4922412413903;3177.984110079458;4915.48387419323;4988.700677959334;4996.875078123039;5002.549770520634;5010.348654674905;3997.420374045776;4194.224068547298;4303.978480107579;4319.654427645813;4329.675274354405;5228.503292187236;5261.210818377236;5277.23480095384;5289.550388216968;5291.444398338196;3379.3241351729507;3392.86428542858;3402.7447941404494;3404.5403870477417;3404.6254911959663;5577.243168401981;5597.832065038015;5602.467765562265;5610.438956104438;5616.887662246773;3334.9833250834076;3357.2110554019787;3396.7452441066525;3431.588209414886;3435.278591495764;3407.9488807667904;3449.6722811332884;3470.375332440185;3480.9825950870086;3483.5819701635965;4098.897527561835;4172.5410204877435;4175.791210439465;4178.958210417857;4179.519355274121;5351.785928562892;5368.758405871745;5375.844193498417;5377.7311134443125;5394.217838413408;1145.6734830573246;1149.4654636060952;1202.8135638975973;1210.7699537087924;1212.248405988293;741.610654406441;755.467395486181;768.8270139218631;772.127495929596;777.0713996773914;5178.197379406198;5198.766055527511;5202.193659982709;5224.798296391851;5243.397009343898;5470.56235501159;5498.195920080708;5516.862078447991;5521.641093328921;5524.281843360418;5197.272381866542;5205.9219111713355;5220.869478262998;5242.475752424727;5242.580593552507;2821.195959151632;2938.4563855686765;2959.9112026638804;2965.629296337958;2969.4803409403266;5912.260967379078;5919.556033297434;5943.257092863392;5947.078202878561;5950.047992321294;5663.348714897839;5664.631798933056;5680.602357834912;5681.204631351614;5698.293310677395;5795.043817770105;5907.763689452459;5929.258842644583;5939.3763654816485;5948.464638182411;5487.012337779238;5499.477549632778;5499.752511136929;5507.972460137674;5535.474129957647;5434;5461.55268853762;5465.945340546703;5483.862903427446;5493.615446918678;5654.519365853948;5902.6458412495385;5924.9703751480965;5948.256467941507;5951.256092988377;3434.261633748756;3446.138465383654;3457.3258214647904;3465.722742180569;3466.913327166841;5523.613347065828;5534.252875861868;5537.335519737658;5547.7780888764;5566.192902029264;5173.3533308336455;5353.571714262772;5381.811636592857;5406.405295417422;5414.756335964812;5151;5315.255864178941;5375.381831089475;5398.46015398457;5426.918596220981;5325.840224793263;5347.946520534901;5351.063563876384;5351.357837059578;5356.250124982427;5218.504242096995;5299.6290259681455;5364.570834333166;5450.127979523258;5475.698836564027;5332.253484512249;5368.812091576776;5388.46115388458;5393.784248630011;5404.945950540444
10:32:05 INFO - PROCESS | 3956 | |5;yui.html;631.2803404119302;662.5660192573863;662.8641128568643;664.6676661669165;722.3318430451833;14323.64190895228;15477.767833482507;15496.612584685388;15607.687846243096;15792.605184870385;13165.605031849036;14050.367733451994;14055.437782488689;14093.647658808535;14126.152430854156;634.0742515926753;646.7994921574303;648.2480322825523;649.7693318871803;650.2067477677226;525.6241787122207;540.2544488605719;645.2901808011197;663.3797399431522;665.0755449924585;692.2523674431627;694.6665600511758;696.1020283833848;696.8501772118993;696.9930300696979;687.7386593094642;689.1557841643996;689.6551724137906;689.7447944260631;690.3096903096903;392.7918203352228;404.46004583880557;493.75065591876233;503.7481259370315;503.9769268385184;13983.720325593431;13986.16083035021;13996.88024958001;13999.580012599637;14013.159210447406;12770.48918043277;12966.56598478324;13018.218906865619;13190.86809131906;13913.652158696012;12641.557545485864;12668.276156696786;12905.419256133402;12919.095663303573;12982.805257921138;4365.279728844736;4392.0776636906285;4407.823687052514;4432.22436073689;4487.282034874404;77981.61009194917;78405.60797196034;79790.20209797943;80234.19765802384;80855.59572202161;10365.222608304408;10624.575016999388;10681.46592670379;10771;11163.106951443864;17189.57026074333;17604.471865844047;17722.291108355523;17772.733408998876;17787.377441789475;7611.086669599684;7639.350655194259;7646.694132234648;7651.885221721679;7863.764087077282;4008.318585840414;4071.2875246832277;4101.22076805412;4189.2459357315975;4291.3777502262;2945.366746149588;3048.222703210667;3067.92330191747;3085.5217441296636;3088.4286407014774;543.6384804105226;550.6668465578348;566.0123085216323;566.6909439195389;566.7817890112336;635.2821311917596;641.6214433484173;663.8871391863395;680.8195828105556;687.2405991379553;9049.864252036225;9263.629454821732;9270.655754915499;9739.56171972263;9750.707478775648;650.6453982579411;670.2392784189882;677.8034370032635;688.0573614148649;710.2640113005627;4419.535948725402;4470.351798989128;4506.551955640636;4513.68404211702;4521.954780452219;106204;110540.44729776299;112530.43734781274;113327;116238;96374;99759.00240997643;100243.49878250709;100807.49596252118;101079;57823.71088144617;81843.58798737983;86763.56618216868;86912.56543717241;89044;8792.516411597424;8795.25240354577;8866.625673020691;8962.372633915564;8982.955085224532;51359.48640513547;52303;52414;52973.73513132564;53582.92834143373;69308.6534567324;73300.63349683218;73485.91654918062;73949.2605073964;76028.61985690036;10733.785324293314;10887.618933337295;11146.219764616399;11377.317360958368;11406.600768973047;15145.697086057997;15174.481221740669;15610.531684049496;15894.68210635804;16036;12118.272903625812;13592.184468931895;13989.300534973007;14672.193029383487;14863.851361486246;14013.229272390117;14110.647233819236;14258.786118208027;14657.56027319182;14859.702805944036;14102.119135686264;14225.14649121056;14504.492342767953;14529.636759081108;14724.484643037442;12280.631581052583;12494.500219991462;12513.374331283581;12601.495940162658;12604.747905042032;12013.939930300292;12038.518459261883;12137.69655758613;12304.200226985216;12388.81416778767
10:32:05 INFO - PROCESS | 3956 | __end_tp_report
10:32:05 INFO - PROCESS | 3956 | __start_cc_report
10:32:05 INFO - PROCESS | 3956 | _x_x_mozilla_cycle_collect,1817
10:32:05 INFO - PROCESS | 3956 | __end_cc_report
10:32:05 INFO - PROCESS | 3956 | __startTimestamp1474824725560__endTimestamp
10:32:05 INFO - PROCESS | 3956 |
10:32:05 INFO - PROCESS | 3956 | ------- Summary: start -------
10:32:05 INFO - PROCESS | 3956 | Number of tests: 6
10:32:05 INFO - PROCESS | 3956 |
10:32:05 INFO - PROCESS | 3956 | [#0] dojo.html Cycles:165 Average:4616.72 Median:4861.56 stddev:600.31 (12.3%) stddev-sans-first:583.14
10:32:05 INFO - PROCESS | 3956 | Values: 2706.3 2727.3 2747.6 2749.2 2753.4 4695.7 4713.2 4796.6 4798.0 4817.2 4694.0 4718.2 4728.3 4753.0 4835.9 4189.3 4205.1 4210.7 4211.1 4226.5 4105.1 4162.8 4212.1 4247.8 4248.6 4106.6 4125.5 4200.3 4206.5 4214.3 3937.7 4124.2 4173.6 4187.1 4192.6 3887.0 3956.2 4078.1 4113.9 4152.9 4601.5 4659.4 4680.5 4739.8 4744.8 4929.6 4943.4 5037.9 5040.2 5051.8 4942.0 4976.9 4991.6 5003.8 5057.0 4634.2 4650.7 4732.4 4738.2 4747.8 4930.9 5073.2 5142.3 5163.5 5171.4 4560.3 4826.0 4828.4 4883.3 4915.7 4852.5 4984.2 5006.8 5019.9 5079.1 4896.9 4908.9 4933.1 5037.0 5039.9 4883.1 4907.4 4922.9 5012.8 5044.6 3329.8 3337.4 3343.4 3366.2 3368.5 3086.1 3090.5 3100.6 3100.8 3102.2 4760.0 4766.1 4831.1 4861.6 4879.0 4892.4 4956.8 4986.7 4988.3 5037.2 4812.7 4860.7 4866.3 4872.8 4878.7 4044.4 4119.6 4164.6 4191.5 4195.0 5178.6 5206.9 5241.4 5341.4 5368.1 4950.6 4963.4 4974.2 4983.6 5039.4 5290.2 5300.0 5307.7 5406.8 5410.6 4949.5 4967.6 5024.3 5058.0 5062.3 4952.7 4965.4 4980.3 5037.7 5119.5 4840.7 4848.6 4871.7 4890.4 4897.9 4856.6 4879.1 4881.6 4884.3 4889.7 4740.0 4794.2 4799.8 4802.1 4809.0 4881.6 4903.6 4908.9 4911.6 4919.8 4877.6 4883.0 4891.2 4892.2 4896.0
10:32:05 INFO - PROCESS | 3956 |
10:32:05 INFO - PROCESS | 3956 | [#1] ext.html Cycles:180 Average:54192.27 Median:39061.40 stddev:105748.19 (270.7%) stddev-sans-first:105968.09
10:32:05 INFO - PROCESS | 3956 | Values: 528.0 552.7 556.1 565.3 583.0 38914.6 40399.6 40405.2 40510.6 42629.6 41832.2 42258.6 42303.6 42308.0 42344.4 8329.5 8363.6 8425.3 8509.4 8521.6 8278.5 8282.2 8311.1 8429.3 8433.7 8469.8 8488.8 8496.2 8498.6 8505.5 8311.2 8366.7 8369.1 8420.0 8426.2 8287.3 8378.5 8451.4 8452.5 8456.7 38527.8 38837.0 39005.6 39056.8 39066.0 3200.7 3287.6 3475.4 3523.2 3798.4 29706.4 30619.8 30954.4 31844.0 31893.6 3386.3 3631.5 3698.8 3720.2 3827.9 241981.0 330343.0 330844.0 330983.0 332409.0 53806.7 54290.7 54305.5 54332.0 54412.5 55747.2 55811.7 57583.7 58310.4 58458.1 13179.4 13546.5 13552.7 13585.0 13607.5 10045.0 10107.5 10128.0 10166.9 10209.0 18001.4 18096.2 18152.8 18228.5 18285.2 7193.7 7281.9 7283.3 7322.7 7339.6 816.0 850.6 868.9 926.9 995.9 28147.7 28233.7 28966.7 29403.3 29460.0 941.4 945.7 959.3 971.4 1065.1 15425.8 15752.4 15807.4 15889.2 16096.4 442697.0 447207.0 447959.0 449114.0 451262.0 50049.7 50169.5 50336.0 50368.2 50491.7 1129.2 1168.3 1175.5 1177.7 1181.7 28008.6 29040.0 29259.3 29275.9 29326.0 43907.0 44289.0 44354.8 44440.9 44557.6 404877.0 425165.0 426275.0 426975.0 429274.0 22465.3 23333.4 23351.2 23377.5 23546.5 42101.6 42505.6 42602.6 42615.1 42687.1 36184.3 36393.6 36922.3 36931.3 37620.1 36395.3 36872.3 37226.0 37553.0 37919.0 34884.8 34931.0 35222.1 35339.8 35592.5 32391.7 32425.8 32607.2 32760.2 33535.5 24754.6 25495.6 25607.8 26066.5 26409.0
10:32:05 INFO - PROCESS | 3956 |
10:32:05 INFO - PROCESS | 3956 | [#2] jquery.html Cycles:180 Average:26100.35 Median:42293.73 stddev:66862.72 (158.1%) stddev-sans-first:67022.18
10:32:05 INFO - PROCESS | 3956 | Values: 279.5 300.0 304.0 311.2 317.0 12836.9 17517.9 18005.8 18153.4 18754.5 17434.0 17707.3 17781.3 17869.6 17970.5 5277.9 5380.9 5416.3 5472.1 5482.6 5230.4 5407.2 5423.9 5432.7 5440.9 5354.4 5369.8 5374.9 5376.1 5376.6 5208.0 5241.2 5300.5 5337.8 5365.2 4510.7 4533.2 4636.0 4639.2 4642.0 21546.7 21984.2 22172.8 22182.8 22216.3 2171.2 2171.9 2175.3 2392.3 2815.7 14095.2 14243.6 14539.2 14819.0 14902.3 1672.2 1721.7 1722.2 1775.3 1784.9 82914.6 83240.6 84625.6 85457.0 86932.0 8567.4 8603.9 8795.5 8856.5 9040.9 10173.9 10559.3 10692.4 11292.8 11299.3 7105.5 7130.2 7133.9 7216.9 7267.5 7372.9 7398.1 7474.4 7485.1 7498.0 5513.9 5680.3 5710.5 5776.8 5899.7 1500.9 1527.8 1528.7 1537.2 1548.5 817.3 885.4 920.0 930.8 971.9 18574.9 19022.4 19073.3 19276.7 19648.2 893.2 924.8 925.4 932.5 942.5 10560.9 11024.8 11038.8 11054.6 11075.2 395922.0 400969.0 403283.0 403987.0 406776.0 53957.7 82881.0 84674.6 85491.1 85639.6 846.7 847.3 860.5 873.4 882.0 16993.2 17426.4 17465.1 17468.4 17679.8 42094.8 42147.7 42439.8 42925.8 43016.0 60906.0 60935.7 62112.4 67739.0 68506.7 7372.0 7460.3 7495.6 7535.9 7585.4 9572.6 13944.6 13964.2 14000.9 14115.4 11404.1 11482.8 11559.1 11593.2 11752.4 11081.6 11257.8 11385.6 11591.6 11595.4 11703.1 11743.6 11756.6 11970.3 12096.0 7281.8 7505.1 7582.4 7642.7 7685.0 10346.5 11450.5 11563.2 11750.4 11756.5
10:32:05 INFO - PROCESS | 3956 |
10:32:05 INFO - PROCESS | 3956 | [#3] mootools.html Cycles:180 Average:4656.74 Median:5861.84 stddev:2582.64 (44.1%) stddev-sans-first:2576.25
10:32:05 INFO - PROCESS | 3956 | Values: 1125.3 1228.1 1238.6 1274.6 1547.3 6299.0 6974.9 7237.3 7277.8 7456.2 7258.0 7330.4 7333.0 7336.6 7343.5 1888.4 1935.4 1942.7 1945.2 1946.0 1895.5 1923.8 1926.3 1930.4 1968.4 1880.1 1921.1 1941.2 1944.9 1949.7 1826.2 1869.9 1871.7 1873.0 1873.9 1823.2 1860.9 1866.2 1870.2 1872.7 7009.3 7054.4 7148.5 7164.2 7175.2 4228.5 4356.1 4439.4 4462.8 4466.9 2778.6 2861.3 2902.0 2902.1 2915.8 773.1 796.4 802.3 807.5 809.0 9201.5 9263.8 9278.4 9279.3 9284.2 3555.8 3571.1 3630.8 3646.3 3647.1 7364.1 7380.8 7382.0 7384.7 7389.6 4265.1 4285.8 4307.3 4321.5 4321.5 3554.5 3665.2 4116.1 4117.8 4120.3 2940.0 3053.3 3085.4 3093.3 3109.8 903.1 904.0 911.8 927.1 927.7 796.5 810.2 811.9 814.4 850.4 4866.9 4867.5 4949.2 4994.4 5069.7 793.4 802.3 818.5 849.8 860.7 3256.2 3388.3 3414.3 3415.4 3478.4 8453.5 8712.8 8742.2 8790.6 8819.7 8526.0 8596.5 8613.4 8702.8 8750.9 6781.6 6831.7 6868.5 6890.1 6892.2 4762.6 4772.8 4828.2 4832.4 4893.3 7997.2 8108.8 8131.1 8211.4 8287.8 8007.3 8157.8 8204.6 8225.6 8278.9 5779.1 5786.9 5851.4 5862.0 5883.5 6236.1 6438.5 6804.5 6998.4 7003.8 6145.1 6308.6 6327.9 6354.9 6408.8 5759.7 6147.7 6319.9 6342.8 6365.7 5735.7 5861.7 5873.6 5876.9 5893.6 4308.6 4360.5 4387.3 4391.0 4478.6 4133.5 4202.4 4220.2 4403.6 4528.3
10:32:05 INFO - PROCESS | 3956 |
10:32:05 INFO - PROCESS | 3956 | [#4] prototype.html Cycles:180 Average:4418.52 Median:5204.06 stddev:1440.16 (27.7%) stddev-sans-first:1409.56
10:32:05 INFO - PROCESS | 3956 | Values: 235.4 236.3 236.9 241.8 247.5 5139.3 5147.2 5182.2 5187.0 5190.4 4878.0 4923.7 5012.7 5054.5 5091.0 3667.4 3698.7 3701.9 3703.6 3716.5 3680.5 3690.2 3693.2 3707.0 3710.2 3535.9 3593.0 3625.6 3686.8 3689.0 3623.8 3673.7 3676.8 3677.2 3685.3 3165.8 3170.5 3171.6 3173.5 3178.0 4915.5 4988.7 4996.9 5002.5 5010.3 3997.4 4194.2 4304.0 4319.7 4329.7 5228.5 5261.2 5277.2 5289.6 5291.4 3379.3 3392.9 3402.7 3404.5 3404.6 5577.2 5597.8 5602.5 5610.4 5616.9 3335.0 3357.2 3396.7 3431.6 3435.3 3407.9 3449.7 3470.4 3481.0 3483.6 4098.9 4172.5 4175.8 4179.0 4179.5 5351.8 5368.8 5375.8 5377.7 5394.2 1145.7 1149.5 1202.8 1210.8 1212.2 741.6 755.5 768.8 772.1 777.1 5178.2 5198.8 5202.2 5224.8 5243.4 5470.6 5498.2 5516.9 5521.6 5524.3 5197.3 5205.9 5220.9 5242.5 5242.6 2821.2 2938.5 2959.9 2965.6 2969.5 5912.3 5919.6 5943.3 5947.1 5950.0 5663.3 5664.6 5680.6 5681.2 5698.3 5795.0 5907.8 5929.3 5939.4 5948.5 5487.0 5499.5 5499.8 5508.0 5535.5 5434.0 5461.6 5465.9 5483.9 5493.6 5654.5 5902.6 5925.0 5948.3 5951.3 3434.3 3446.1 3457.3 3465.7 3466.9 5523.6 5534.3 5537.3 5547.8 5566.2 5173.4 5353.6 5381.8 5406.4 5414.8 5151.0 5315.3 5375.4 5398.5 5426.9 5325.8 5347.9 5351.1 5351.4 5356.3 5218.5 5299.6 5364.6 5450.1 5475.7 5332.3 5368.8 5388.5 5393.8 5404.9
10:32:05 INFO - PROCESS | 3956 |
10:32:05 INFO - PROCESS | 3956 | [#5] yui.html Cycles:180 Average:20455.22 Median:4378.68 stddev:29609.02 (676.2%) stddev-sans-first:29654.67
10:32:05 INFO - PROCESS | 3956 | Values: 631.3 662.6 662.9 664.7 722.3 14323.6 15477.8 15496.6 15607.7 15792.6 13165.6 14050.4 14055.4 14093.6 14126.2 634.1 646.8 648.2 649.8 650.2 525.6 540.3 645.3 663.4 665.1 692.3 694.7 696.1 696.9 697.0 687.7 689.2 689.7 689.7 690.3 392.8 404.5 493.8 503.7 504.0 13983.7 13986.2 13996.9 13999.6 14013.2 12770.5 12966.6 13018.2 13190.9 13913.7 12641.6 12668.3 12905.4 12919.1 12982.8 4365.3 4392.1 4407.8 4432.2 4487.3 77981.6 78405.6 79790.2 80234.2 80855.6 10365.2 10624.6 10681.5 10771.0 11163.1 17189.6 17604.5 17722.3 17772.7 17787.4 7611.1 7639.4 7646.7 7651.9 7863.8 4008.3 4071.3 4101.2 4189.2 4291.4 2945.4 3048.2 3067.9 3085.5 3088.4 543.6 550.7 566.0 566.7 566.8 635.3 641.6 663.9 680.8 687.2 9049.9 9263.6 9270.7 9739.6 9750.7 650.6 670.2 677.8 688.1 710.3 4419.5 4470.4 4506.6 4513.7 4522.0 106204.0 110540.4 112530.4 113327.0 116238.0 96374.0 99759.0 100243.5 100807.5 101079.0 57823.7 81843.6 86763.6 86912.6 89044.0 8792.5 8795.3 8866.6 8962.4 8983.0 51359.5 52303.0 52414.0 52973.7 53582.9 69308.7 73300.6 73485.9 73949.3 76028.6 10733.8 10887.6 11146.2 11377.3 11406.6 15145.7 15174.5 15610.5 15894.7 16036.0 12118.3 13592.2 13989.3 14672.2 14863.9 14013.2 14110.6 14258.8 14657.6 14859.7 14102.1 14225.1 14504.5 14529.6 14724.5 12280.6 12494.5 12513.4 12601.5 12604.7 12013.9 12038.5 12137.7 12304.2 12388.8
10:32:05 INFO - PROCESS | 3956 | -------- Summary: end --------
10:32:05 INFO - PROCESS | 3956 |
10:32:06 INFO - TEST-INFO | 3956: exit 0
10:32:06 INFO - TEST-OK | dromaeo_css | took 1116825ms
10:32:06 INFO - TEST-START | kraken
10:32:06 INFO - operating with platform_type : w7_
10:32:06 INFO - Initialising browser for kraken test...
10:32:06 INFO - TEST-INFO | started process 2648 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpdraav8\profile http://localhost:50002/getInfo.html)
10:32:11 INFO - PROCESS | 2648 | __metrics Screen width/height:1600/1200
10:32:11 INFO - PROCESS | 2648 | colorDepth:24
10:32:11 INFO - PROCESS | 2648 | Browser inner width/height: 1010/674
10:32:11 INFO - PROCESS | 2648 | __metrics
10:32:12 INFO - PROCESS | 2648 | 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"
10:32:12 INFO - PROCESS | 2648 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
10:32:12 INFO - PROCESS | 2648 | console.error:
10:32:12 INFO - PROCESS | 2648 | Message: Error: Connection closed before committing the transaction.
10:32:12 INFO - PROCESS | 2648 | Stack:
10:32:12 INFO - PROCESS | 2648 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:937:23
10:32:12 INFO - PROCESS | 2648 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7
10:32:12 INFO - PROCESS | 2648 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11
10:32:12 INFO - PROCESS | 2648 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7
10:32:12 INFO - PROCESS | 2648 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7
10:32:12 INFO - PROCESS | 2648 | TaskImpl_run@resource://gre/modules/Task.jsm:324:13
10:32:12 INFO - PROCESS | 2648 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9
10:32:12 INFO - PROCESS | 2648 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15
10:32:12 INFO - PROCESS | 2648 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5
10:32:12 INFO - PROCESS | 2648 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29
10:32:12 INFO - PROCESS | 2648 | Barrier/this.client.addBlocker/promise13.2%
10:33:10 INFO - TinderboxPrint: I/O read bytes / time
63,371,264 / 677,760
10:33:10 INFO - TinderboxPrint: I/O write bytes / time
439,100,928 / 3,371,230
10:33:10 INFO - TinderboxPrint: CPU idle
8,221.4 (86.9%)
10:33:10 INFO - TinderboxPrint: CPU user
1,210.0 (12.8%)
10:33:10 INFO - install - Wall time: 3s; CPU: 11.0%; Read bytes: 0; Write bytes: 35083776; Read time: 0; Write time: 252800
10:33:10 INFO - run-tests - Wall time: 1181s; CPU: 13.0%; Read bytes: 63371264; Write bytes: 366644736; Read time: 677760; Write time: 2995530
10:33:10 INFO - Running post-run listener: _upload_blobber_files
10:33:10 INFO - Blob upload gear active.
10:33:10 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir.
10:33:10 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
10:33:10 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']
10:33:10 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
10:33:11 INFO - (blobuploader) - INFO - Open directory for files ...
10:33:11 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log ...
10:33:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
10:33:11 INFO - (blobuploader) - INFO - Uploading, attempt #1.
10:33:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded
10:33:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
10:33:12 INFO - (blobuploader) - INFO - Done attempting.
10:33:12 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log ...
10:33:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
10:33:12 INFO - (blobuploader) - INFO - Uploading, attempt #1.
10:33:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded
10:33:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
10:33:13 INFO - (blobuploader) - INFO - Done attempting.
10:33:13 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ...
10:33:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
10:33:13 INFO - (blobuploader) - INFO - Uploading, attempt #1.
10:33:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
10:33:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
10:33:20 INFO - (blobuploader) - INFO - Done attempting.
10:33:20 INFO - (blobuploader) - INFO - Iteration through files over.
10:33:20 INFO - Return code: 0
10:33:20 INFO - rmtree: C:\slave\test\build\uploaded_files.json
10:33:20 INFO - Using _rmtree_windows ...
10:33:20 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1
10:33:20 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f8f81379b8dd85e0096230b452a8e829b81d4fa2119417730f41c2e1632aed66eecbe9600eaeb32123df72c6c3ac51166f8cb7d6d1c54884f3a6a3f80bd6c92a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d6e8a7ae8f3d5244fd845d55c5ea32e1db0c9906682f2666b6f7ff9348d5903a1f76b705863bd9997d1b08dfd3b27f58eb00607a7e1fe334cc85400d6c515ec6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3752c1be3e91c90e4e8a2fe3de4a414417178fe41274ac7010f7b04afb156b74df91dd8f0c7875437d2d1764a7dd972b0307f095c0f3aa837e297871694dc60f"}
10:33:20 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files
10:33:20 INFO - Writing to file C:\slave\test\properties\blobber_files
10:33:20 INFO - Contents:
10:33:20 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f8f81379b8dd85e0096230b452a8e829b81d4fa2119417730f41c2e1632aed66eecbe9600eaeb32123df72c6c3ac51166f8cb7d6d1c54884f3a6a3f80bd6c92a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d6e8a7ae8f3d5244fd845d55c5ea32e1db0c9906682f2666b6f7ff9348d5903a1f76b705863bd9997d1b08dfd3b27f58eb00607a7e1fe334cc85400d6c515ec6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3752c1be3e91c90e4e8a2fe3de4a414417178fe41274ac7010f7b04afb156b74df91dd8f0c7875437d2d1764a7dd972b0307f095c0f3aa837e297871694dc60f"}
10:33:20 INFO - Running post-run listener: copy_logs_to_upload_dir
10:33:20 INFO - Copying logs to upload dir...
10:33:20 INFO - mkdir: C:\slave\test\build\upload\logs
10:33:20 INFO - Copying logs to upload dir...
10:33:20 INFO - Using _rmtree_windows ...
10:33:20 INFO - Using _rmtree_windows ...
10:33:20 INFO - Using _rmtree_windows ...
10:33:20 INFO - Using _rmtree_windows ...
10:33:20 INFO - Using _rmtree_windows ...
10:33:20 INFO - Using _rmtree_windows ...
10:33:20 INFO - Using _rmtree_windows ...
program finished with exit code 0
elapsedTime=1369.972000
========= master_lag: -0.01 =========
========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 22 mins, 49 secs) (at 2016-09-25 10:33:21.000852) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-25 10:33:21.001408) =========
'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-138
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-138
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-138
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/f8f81379b8dd85e0096230b452a8e829b81d4fa2119417730f41c2e1632aed66eecbe9600eaeb32123df72c6c3ac51166f8cb7d6d1c54884f3a6a3f80bd6c92a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d6e8a7ae8f3d5244fd845d55c5ea32e1db0c9906682f2666b6f7ff9348d5903a1f76b705863bd9997d1b08dfd3b27f58eb00607a7e1fe334cc85400d6c515ec6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3752c1be3e91c90e4e8a2fe3de4a414417178fe41274ac7010f7b04afb156b74df91dd8f0c7875437d2d1764a7dd972b0307f095c0f3aa837e297871694dc60f"}
build_url:https://queue.taskcluster.net/v1/task/HcsnZxEbQPiBMFYuFdrp8A/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/HcsnZxEbQPiBMFYuFdrp8A/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/f8f81379b8dd85e0096230b452a8e829b81d4fa2119417730f41c2e1632aed66eecbe9600eaeb32123df72c6c3ac51166f8cb7d6d1c54884f3a6a3f80bd6c92a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d6e8a7ae8f3d5244fd845d55c5ea32e1db0c9906682f2666b6f7ff9348d5903a1f76b705863bd9997d1b08dfd3b27f58eb00607a7e1fe334cc85400d6c515ec6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3752c1be3e91c90e4e8a2fe3de4a414417178fe41274ac7010f7b04afb156b74df91dd8f0c7875437d2d1764a7dd972b0307f095c0f3aa837e297871694dc60f"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-25 10:33:21.121785) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-25 10:33:21.122595) =========
'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-138
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-138
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-138
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.02 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-25 10:33:21.238802) =========
========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-09-25 10:33:21.239543) =========
========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-09-25 10:33:21.741054) =========
========= Total master_lag: 0.15 =========