builder: autoland_win7_ix_test-dromaeojs
slave: t-w732-ix-035
starttime: 1474993299.96
results: success (0)
buildid: 20160927070154
builduid: ccd6271b4b2c42b5aa41a2e29df4ed8c
revision: 8009d19e29e0c4312cbc5f6dca6c32697dafd46f
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-27 09:21:39.960311) =========
master: http://buildbot-master127.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-27 09:21:39.960758) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-27 09:21:39.961083) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-27 09:21:40.156624) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-27 09:21:40.157308) =========
'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-035
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-035
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-035
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
--09:21:41-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
=> `archiver_client.py'
Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102
Connecting to hg.mozilla.org|63.245.215.25|:443... connected.
WARNING: Certificate verification error for hg.mozilla.org: certificate signature failure
HTTP request sent, awaiting response... 200 Script output follows
Length: 12,141 (12K) [text/x-python]
0K .......... . 100% 8.56 MB/s
09:21:41 (8.56 MB/s) - `archiver_client.py' saved [12141/12141]
program finished with exit code 0
elapsedTime=1.150000
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-27 09:21:41.326222) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 10 secs) (at 2016-09-27 09:21:41.326579) =========
'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-035
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-035
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-035
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=10.201000
========= master_lag: 0.01 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 10 secs) (at 2016-09-27 09:21:51.541237) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-27 09:21:51.542041) =========
'bash' '-c' u'python archiver_client.py mozharness --repo integration/autoland --rev 8009d19e29e0c4312cbc5f6dca6c32697dafd46f --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 8009d19e29e0c4312cbc5f6dca6c32697dafd46f --destination scripts --debug']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-035
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-035
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-035
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-27 09:21:52,371 truncating revision to first 12 chars
2016-09-27 09:21:52,371 Setting DEBUG logging.
2016-09-27 09:21:52,371 attempt 1/10
2016-09-27 09:21:52,371 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/8009d19e29e0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2016-09-27 09:21:52,944 unpacking tar archive at: autoland-8009d19e29e0/testing/mozharness/
2016-09-27 09:21:53,092 skipping symlink on windows: configs/single_locale/linux32.py
program finished with exit code 0
elapsedTime=1.395000
========= master_lag: 0.14 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-27 09:21:53.078329) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-27 09:21:53.079068) =========
script_repo_revision: 8009d19e29e0c4312cbc5f6dca6c32697dafd46f
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-27 09:21:53.079516) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-27 09:21:53.079831) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-27 09:21:53.090687) =========
========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 25 mins, 47 secs) (at 2016-09-27 09:21:53.091210) =========
'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Autoland-Non-PGO' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Autoland-Non-PGO'
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-Non-PGO', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland-Non-PGO']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-035
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-035
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-035
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
09:21:53 INFO - MultiFileLogger online at 20160927 09:21:53 in C:\slave\test
09:21:53 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO
09:21:53 INFO - Dumping config to C:\slave\test\logs\localconfig.json.
09:21:53 INFO - {'append_to_log': False,
09:21:53 INFO - 'base_work_dir': 'C:\\slave\\test',
09:21:53 INFO - 'blob_upload_branch': 'Autoland-Non-PGO',
09:21:53 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt',
09:21:53 INFO - 'branch': 'Autoland-Non-PGO',
09:21:53 INFO - 'buildbot_json_path': 'buildprops.json',
09:21:53 INFO - 'config_files': ('talos/windows_config.py',),
09:21:53 INFO - 'default_actions': ('clobber',
09:21:53 INFO - 'read-buildbot-config',
09:21:53 INFO - 'download-and-extract',
09:21:53 INFO - 'populate-webroot',
09:21:53 INFO - 'create-virtualenv',
09:21:53 INFO - 'install',
09:21:53 INFO - 'run-tests'),
09:21:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
09:21:53 INFO - 'download_minidump_stackwalk': True,
09:21:53 INFO - 'download_symbols': 'ondemand',
09:21:53 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python',
09:21:53 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'),
09:21:53 INFO - 'hg': 'c:/mozilla-build/hg/hg',
09:21:53 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python',
09:21:53 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'),
09:21:53 INFO - 'python': 'c:/mozilla-build/python27/python.exe',
09:21:53 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe',
09:21:53 INFO - 'C:/mozilla-build/tooltool.py'),
09:21:53 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe',
09:21:53 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')},
09:21:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
09:21:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
09:21:53 INFO - 'installer_path': 'installer.exe',
09:21:53 INFO - 'log_level': 'info',
09:21:53 INFO - 'log_name': 'talos',
09:21:53 INFO - 'log_to_console': True,
09:21:53 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe',
09:21:53 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe',
09:21:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest',
09:21:53 INFO - 'opt_config_files': (),
09:21:53 INFO - 'pip_index': False,
09:21:53 INFO - 'sps_profile': False,
09:21:53 INFO - 'sps_profile_interval': 0,
09:21:53 INFO - 'suite': 'dromaeojs',
09:21:53 INFO - 'system_bits': '32',
09:21:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
09:21:53 INFO - 'title': 'T-W732-IX-035',
09:21:53 INFO - 'use_talos_json': True,
09:21:53 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'),
09:21:53 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv',
09:21:53 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll',
09:21:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
09:21:53 INFO - 'work_dir': 'build'}
09:21:53 INFO - [mozharness: 2016-09-27 16:21:53.784000Z] Running clobber step.
09:21:53 INFO - Running pre-action listener: _resource_record_pre_action
09:21:53 INFO - Running main action method: clobber
09:21:53 INFO - rmtree: C:\slave\test\build
09:21:53 INFO - Using _rmtree_windows ...
09:21:53 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1
09:23:37 INFO - Running post-action listener: _resource_record_post_action
09:23:37 INFO - [mozharness: 2016-09-27 16:23:37.345000Z] Finished clobber step (success)
09:23:37 INFO - [mozharness: 2016-09-27 16:23:37.345000Z] Running read-buildbot-config step.
09:23:37 INFO - Running pre-action listener: _resource_record_pre_action
09:23:37 INFO - Running main action method: read_buildbot_config
09:23:37 INFO - Using buildbot properties:
09:23:37 INFO - {
09:23:37 INFO - "project": "",
09:23:37 INFO - "product": "firefox",
09:23:37 INFO - "script_repo_revision": "production",
09:23:37 INFO - "builddir": "autoland_win7_ix_test-dromaeojs",
09:23:37 INFO - "repository": "",
09:23:37 INFO - "buildername": "Windows 7 32-bit autoland talos dromaeojs",
09:23:37 INFO - "buildid": "20160927070154",
09:23:37 INFO - "pgo_build": "False",
09:23:37 INFO - "basedir": "C:\\slave\\test",
09:23:37 INFO - "buildnumber": 253,
09:23:37 INFO - "slavename": "t-w732-ix-035",
09:23:37 INFO - "master": "http://buildbot-master127.bb.releng.scl3.mozilla.com:8201/",
09:23:37 INFO - "platform": "win7_ix",
09:23:37 INFO - "scheduler": "tests-autoland-win32-talos",
09:23:37 INFO - "branch": "autoland",
09:23:37 INFO - "repo_path": "integration/autoland",
09:23:37 INFO - "revision": "8009d19e29e0c4312cbc5f6dca6c32697dafd46f",
09:23:37 INFO - "stage_platform": "win32",
09:23:37 INFO - "builduid": "ccd6271b4b2c42b5aa41a2e29df4ed8c",
09:23:37 INFO - "slavebuilddir": "test"
09:23:37 INFO - }
09:23:37 INFO - Found installer url https://queue.taskcluster.net/v1/task/Q1XRv_39T_i19CV2BUuI_A/artifacts/public/build/firefox-52.0a1.en-US.win32.zip.
09:23:37 INFO - Running post-action listener: _resource_record_post_action
09:23:37 INFO - [mozharness: 2016-09-27 16:23:37.349000Z] Finished read-buildbot-config step (success)
09:23:37 INFO - [mozharness: 2016-09-27 16:23:37.349000Z] Running download-and-extract step.
09:23:37 INFO - Running pre-action listener: _resource_record_pre_action
09:23:37 INFO - Running main action method: download_and_extract
09:23:37 INFO - mkdir: C:\slave\test\build\tests
09:23:37 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')]}
09:23:37 INFO - trying https://queue.taskcluster.net/v1/task/Q1XRv_39T_i19CV2BUuI_A/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json
09:23:37 INFO - Downloading https://queue.taskcluster.net/v1/task/Q1XRv_39T_i19CV2BUuI_A/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
09:23:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q1XRv_39T_i19CV2BUuI_A/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
09:23:38 INFO - Downloaded 1274 bytes.
09:23:38 INFO - Reading from file C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json
09:23:38 INFO - Using the following test package requirements:
09:23:38 INFO - {u'common': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
09:23:38 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
09:23:38 INFO - u'firefox-52.0a1.en-US.win32.cppunittest.tests.zip'],
09:23:38 INFO - u'gtest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
09:23:38 INFO - u'firefox-52.0a1.en-US.win32.gtest.tests.zip'],
09:23:38 INFO - u'jittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
09:23:38 INFO - u'jsshell-win32.zip'],
09:23:38 INFO - u'mochitest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
09:23:38 INFO - u'firefox-52.0a1.en-US.win32.mochitest.tests.zip'],
09:23:38 INFO - u'mozbase': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
09:23:38 INFO - u'reftest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
09:23:38 INFO - u'firefox-52.0a1.en-US.win32.reftest.tests.zip'],
09:23:38 INFO - u'talos': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
09:23:38 INFO - u'firefox-52.0a1.en-US.win32.talos.tests.zip'],
09:23:38 INFO - u'web-platform': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
09:23:38 INFO - u'firefox-52.0a1.en-US.win32.web-platform.tests.zip'],
09:23:38 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
09:23:38 INFO - u'firefox-52.0a1.en-US.win32.xpcshell.tests.zip']}
09:23:38 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip'] for test suite category: common
09:23:38 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/Q1XRv_39T_i19CV2BUuI_A/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip
09:23:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q1XRv_39T_i19CV2BUuI_A/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1
09:23:38 INFO - Fetch https://queue.taskcluster.net/v1/task/Q1XRv_39T_i19CV2BUuI_A/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory
09:23:39 INFO - Expected file size: 17322596
09:24:58 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
09:24:58 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/Q1XRv_39T_i19CV2BUuI_A/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip
09:24:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q1XRv_39T_i19CV2BUuI_A/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1
09:24:58 INFO - Fetch https://queue.taskcluster.net/v1/task/Q1XRv_39T_i19CV2BUuI_A/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory
09:24:59 INFO - Expected file size: 17322596
09:25:29 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/Q1XRv_39T_i19CV2BUuI_A/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip
09:25:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q1XRv_39T_i19CV2BUuI_A/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip'}, attempt #1
09:25:29 INFO - Fetch https://queue.taskcluster.net/v1/task/Q1XRv_39T_i19CV2BUuI_A/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip into memory
09:25:30 INFO - Expected file size: 14414057
09:25: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')]}
09:25:55 INFO - trying https://queue.taskcluster.net/v1/task/Q1XRv_39T_i19CV2BUuI_A/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
09:25:55 INFO - Downloading https://queue.taskcluster.net/v1/task/Q1XRv_39T_i19CV2BUuI_A/artifacts/public/build/firefox-52.0a1.en-US.win32.zip to C:\slave\test\build\firefox-52.0a1.en-US.win32.zip
09:25:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q1XRv_39T_i19CV2BUuI_A/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
09:25:58 INFO - Downloaded 53518377 bytes.
09:25:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Q1XRv_39T_i19CV2BUuI_A/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
09:25:58 INFO - mkdir: C:\slave\test\properties
09:25:58 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url
09:25:58 INFO - Writing to file C:\slave\test\properties\build_url
09:25:58 INFO - Contents:
09:25:58 INFO - build_url:https://queue.taskcluster.net/v1/task/Q1XRv_39T_i19CV2BUuI_A/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
09:25:59 INFO - Running post-action listener: _resource_record_post_action
09:25:59 INFO - Running post-action listener: set_extra_try_arguments
09:25:59 INFO - [mozharness: 2016-09-27 16:25:59.305000Z] Finished download-and-extract step (success)
09:25:59 INFO - [mozharness: 2016-09-27 16:25:59.305000Z] Running populate-webroot step.
09:25:59 INFO - Running pre-action listener: _resource_record_pre_action
09:25:59 INFO - Running main action method: populate_webroot
09:25:59 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']},
09:25:59 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']},
09:25:59 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome',
09:25:59 INFO - u'--tppagecycles',
09:25:59 INFO - u'7'],
09:25:59 INFO - u'tests': [u'tsvgm']}},
09:25:59 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'],
09:25:59 INFO - u'tests': [u'tresize', u'tcanvasmark']},
09:25:59 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']},
09:25:59 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'],
09:25:59 INFO - u'tests': [u'dromaeo_css', u'kraken']},
09:25:59 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']},
09:25:59 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
09:25:59 INFO - u'talos_options': [u'--disable-e10s'],
09:25:59 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
09:25:59 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
09:25:59 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
09:25:59 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
09:25:59 INFO - u'talos_options': [u'--disable-e10s'],
09:25:59 INFO - u'tests': [u'damp', u'tps']},
09:25:59 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
09:25:59 INFO - u'tests': [u'damp', u'tps']},
09:25:59 INFO - u'g3': {u'talos_options': [u'--disable-e10s'],
09:25:59 INFO - u'tests': [u'dromaeo_dom']},
09:25:59 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']},
09:25:59 INFO - u'g4': {u'talos_options': [u'--disable-e10s'],
09:25:59 INFO - u'tests': [u'basic_compositor_video']},
09:25:59 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']},
09:25:59 INFO - u'other': {u'talos_options': [u'--disable-e10s'],
09:25:59 INFO - u'tests': [u'a11yr',
09:25:59 INFO - u'ts_paint',
09:25:59 INFO - u'tpaint',
09:25:59 INFO - u'sessionrestore',
09:25:59 INFO - u'sessionrestore_no_auto_restore',
09:25:59 INFO - u'tabpaint']},
09:25:59 INFO - u'other-e10s': {u'tests': [u'a11yr',
09:25:59 INFO - u'ts_paint',
09:25:59 INFO - u'tpaint',
09:25:59 INFO - u'sessionrestore',
09:25:59 INFO - u'sessionrestore_no_auto_restore',
09:25:59 INFO - u'tabpaint']},
09:25:59 INFO - u'other-e10s_l64': {u'tests': [u'a11yr',
09:25:59 INFO - u'ts_paint',
09:25:59 INFO - u'tpaint',
09:25:59 INFO - u'sessionrestore',
09:25:59 INFO - u'sessionrestore_no_auto_restore',
09:25:59 INFO - u'tabpaint']},
09:25:59 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr',
09:25:59 INFO - u'ts_paint',
09:25:59 INFO - u'tpaint',
09:25:59 INFO - u'sessionrestore',
09:25:59 INFO - u'sessionrestore_no_auto_restore',
09:25:59 INFO - u'tabpaint']},
09:25:59 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'],
09:25:59 INFO - u'tests': [u'a11yr',
09:25:59 INFO - u'ts_paint',
09:25:59 INFO - u'tpaint',
09:25:59 INFO - u'sessionrestore',
09:25:59 INFO - u'sessionrestore_no_auto_restore',
09:25:59 INFO - u'tabpaint']},
09:25:59 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'],
09:25:59 INFO - u'tests': [u'a11yr',
09:25:59 INFO - u'ts_paint',
09:25:59 INFO - u'tpaint',
09:25:59 INFO - u'sessionrestore',
09:25:59 INFO - u'sessionrestore_no_auto_restore',
09:25:59 INFO - u'tabpaint']},
09:25:59 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'],
09:25:59 INFO - u'tests': [u'tsvgx',
09:25:59 INFO - u'tsvgr_opacity',
09:25:59 INFO - u'tart',
09:25:59 INFO - u'tscrollx',
09:25:59 INFO - u'cart']},
09:25:59 INFO - u'svgr-e10s': {u'tests': [u'tsvgx',
09:25:59 INFO - u'tsvgr_opacity',
09:25:59 INFO - u'tart',
09:25:59 INFO - u'tscrollx',
09:25:59 INFO - u'cart']},
09:25:59 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
09:25:59 INFO - u'talos_options': [u'--disable-e10s'],
09:25:59 INFO - u'tests': [u'tp5o']},
09:25:59 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
09:25:59 INFO - u'tests': [u'tp5o']},
09:25:59 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
09:25:59 INFO - u'talos_options': [u'--disable-e10s',
09:25:59 INFO - u'--xperf_path',
09:25:59 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
09:25:59 INFO - u'tests': [u'tp5n']},
09:25:59 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
09:25:59 INFO - u'talos_options': [u'--xperf_path',
09:25:59 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
09:25:59 INFO - u'tests': [u'tp5n']}},
09:25:59 INFO - u'talos.zip': {u'path': u'',
09:25:59 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}}
09:25:59 INFO - Running post-action listener: _resource_record_post_action
09:25:59 INFO - [mozharness: 2016-09-27 16:25:59.346000Z] Finished populate-webroot step (success)
09:25:59 INFO - [mozharness: 2016-09-27 16:25:59.346000Z] Running create-virtualenv step.
09:25:59 INFO - Running pre-action listener: _resource_record_pre_action
09:25:59 INFO - Running main action method: create_virtualenv
09:25:59 INFO - Creating virtualenv C:\slave\test\build/venv
09:25:59 INFO - mkdir: C:\slave\test\build\venv\Scripts
09:25:59 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll
09:25:59 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'!
09:25:59 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
09:25:59 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv
09:26:23 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe
09:26:37 INFO - Installing distribute......................................................................................................................................................................................done.
09:26:38 INFO - Return code: 0
09:26:38 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version']
09:26:38 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version
09:26:39 INFO - Reading from file tmpfile_stdout
09:26:39 INFO - Output received:
09:26:39 INFO - pip 0.8.2 from c:\slave\test\build\venv\lib\site-packages\pip-0.8.2-py2.7.egg (python 2.7)
09:26:39 INFO - Using _rmtree_windows ...
09:26:39 INFO - Using _rmtree_windows ...
09:26:39 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv
09:26:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
09:26:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
09:26:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
09:26:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
09:26:39 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01840640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018E7E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017EB228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018F4E90>, '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 0x0130A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F6E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-035', 'USERDOMAIN': 'T-W732-IX-035', '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-035', '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
09:26:39 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in C:\slave\test\build
09:26:39 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5
09:26:39 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
09:26:39 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
09:26:39 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
09:26:39 INFO - 'COMPUTERNAME': 'T-W732-IX-035',
09:26:39 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
09:26:39 INFO - 'CYGWINBASE': 'C:\\cygwin',
09:26:39 INFO - 'DCLOCATION': 'SCL3',
09:26:39 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
09:26:39 INFO - 'FP_NO_HOST_CHECK': 'NO',
09:26:39 INFO - 'HOMEDRIVE': 'C:',
09:26:39 INFO - 'HOMEPATH': '\\Users\\cltbld',
09:26:39 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
09:26:39 INFO - 'KTS_VERSION': '1.19c',
09:26:39 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
09:26:39 INFO - 'LOGONSERVER': '\\\\T-W732-IX-035',
09:26:39 INFO - 'MONDIR': 'C:\\Monitor_config\\',
09:26:39 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
09:26:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
09:26:39 INFO - 'MOZ_NO_REMOTE': '1',
09:26:39 INFO - 'NO_EM_RESTART': '1',
09:26:39 INFO - 'NUMBER_OF_PROCESSORS': '8',
09:26:39 INFO - 'OS': 'Windows_NT',
09:26:39 INFO - 'OURDRIVE': 'C:',
09:26:39 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
09:26:39 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
09:26:39 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
09:26:39 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
09:26:39 INFO - 'PROCESSOR_LEVEL': '6',
09:26:39 INFO - 'PROCESSOR_REVISION': '1e05',
09:26:39 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
09:26:39 INFO - 'PROGRAMFILES': 'C:\\Program Files',
09:26:39 INFO - 'PROMPT': '$P$G',
09:26:39 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
09:26:39 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
09:26:39 INFO - 'PUBLIC': 'C:\\Users\\Public',
09:26:39 INFO - 'PWD': 'C:\\slave\\test',
09:26:39 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
09:26:39 INFO - 'SLAVEDIR': 'C:\\slave\\',
09:26:39 INFO - 'SYSTEMDRIVE': 'C:',
09:26:39 INFO - 'SYSTEMROOT': 'C:\\windows',
09:26:39 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:26:39 INFO - 'TEST1': 'testie',
09:26:39 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:26:39 INFO - 'USERDOMAIN': 'T-W732-IX-035',
09:26:39 INFO - 'USERNAME': 'cltbld',
09:26:39 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
09:26:39 INFO - 'WINDIR': 'C:\\windows',
09:26:39 INFO - 'WINDOWS_TRACING_FLAGS': '3',
09:26:39 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
09:26:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
09:26:40 INFO - Ignoring indexes: http://pypi.python.org/simple/
09:26:40 INFO - Downloading/unpacking pip>=1.5
09:26:45 INFO - Running setup.py egg_info for package pip
09:26:45 INFO - warning: no files found matching 'pip\cacert.pem'
09:26:45 INFO - warning: no files found matching '*.html' under directory 'docs'
09:26:45 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
09:26:45 INFO - no previously-included directories found matching 'docs\_build\_sources'
09:26:45 INFO - Installing collected packages: pip
09:26:45 INFO - Found existing installation: pip 0.8.2
09:26:45 INFO - Uninstalling pip:
09:26:45 INFO - Successfully uninstalled pip
09:26:45 INFO - Running setup.py install for pip
09:26:45 INFO - warning: no files found matching 'pip\cacert.pem'
09:26:45 INFO - warning: no files found matching '*.html' under directory 'docs'
09:26:45 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
09:26:45 INFO - no previously-included directories found matching 'docs\_build\_sources'
09:26:45 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts
09:26:45 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts
09:26:45 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts
09:26:45 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts
09:26:45 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts
09:26:45 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts
09:26:45 INFO - Successfully installed pip
09:26:45 INFO - Cleaning up...
09:26:45 INFO - Return code: 0
09:26:45 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv
09:26:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
09:26:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
09:26:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
09:26:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
09:26:45 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 0x01840640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018E7E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017EB228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018F4E90>, '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 0x0130A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F6E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-035', 'USERDOMAIN': 'T-W732-IX-035', '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-035', '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
09:26:45 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
09:26:45 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
09:26:45 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
09:26:45 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
09:26:45 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
09:26:45 INFO - 'COMPUTERNAME': 'T-W732-IX-035',
09:26:45 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
09:26:45 INFO - 'CYGWINBASE': 'C:\\cygwin',
09:26:45 INFO - 'DCLOCATION': 'SCL3',
09:26:45 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
09:26:45 INFO - 'FP_NO_HOST_CHECK': 'NO',
09:26:45 INFO - 'HOMEDRIVE': 'C:',
09:26:45 INFO - 'HOMEPATH': '\\Users\\cltbld',
09:26:45 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
09:26:45 INFO - 'KTS_VERSION': '1.19c',
09:26:45 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
09:26:45 INFO - 'LOGONSERVER': '\\\\T-W732-IX-035',
09:26:45 INFO - 'MONDIR': 'C:\\Monitor_config\\',
09:26:45 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
09:26:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
09:26:45 INFO - 'MOZ_NO_REMOTE': '1',
09:26:45 INFO - 'NO_EM_RESTART': '1',
09:26:45 INFO - 'NUMBER_OF_PROCESSORS': '8',
09:26:45 INFO - 'OS': 'Windows_NT',
09:26:45 INFO - 'OURDRIVE': 'C:',
09:26:45 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
09:26:45 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
09:26:45 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
09:26:45 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
09:26:45 INFO - 'PROCESSOR_LEVEL': '6',
09:26:45 INFO - 'PROCESSOR_REVISION': '1e05',
09:26:45 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
09:26:45 INFO - 'PROGRAMFILES': 'C:\\Program Files',
09:26:45 INFO - 'PROMPT': '$P$G',
09:26:45 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
09:26:45 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
09:26:45 INFO - 'PUBLIC': 'C:\\Users\\Public',
09:26:45 INFO - 'PWD': 'C:\\slave\\test',
09:26:45 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
09:26:45 INFO - 'SLAVEDIR': 'C:\\slave\\',
09:26:45 INFO - 'SYSTEMDRIVE': 'C:',
09:26:45 INFO - 'SYSTEMROOT': 'C:\\windows',
09:26:45 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:26:45 INFO - 'TEST1': 'testie',
09:26:45 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:26:45 INFO - 'USERDOMAIN': 'T-W732-IX-035',
09:26:45 INFO - 'USERNAME': 'cltbld',
09:26:45 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
09:26:45 INFO - 'WINDIR': 'C:\\windows',
09:26:45 INFO - 'WINDOWS_TRACING_FLAGS': '3',
09:26:45 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
09:26:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
09:26:50 INFO - Ignoring indexes: https://pypi.python.org/simple/
09:26:50 INFO - Downloading/unpacking psutil>=3.1.1
09:26:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
09:26:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
09:26:51 INFO - Installing collected packages: psutil
09:26:51 INFO - Successfully installed psutil
09:26:51 INFO - Cleaning up...
09:26:51 INFO - Return code: 0
09:26:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv
09:26:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
09:26:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
09:26:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
09:26:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
09:26:51 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01840640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018E7E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017EB228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018F4E90>, '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 0x0130A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F6E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-035', 'USERDOMAIN': 'T-W732-IX-035', '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-035', '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
09:26:51 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in C:\slave\test\build
09:26:51 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3
09:26:51 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
09:26:51 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
09:26:51 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
09:26:51 INFO - 'COMPUTERNAME': 'T-W732-IX-035',
09:26:51 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
09:26:51 INFO - 'CYGWINBASE': 'C:\\cygwin',
09:26:51 INFO - 'DCLOCATION': 'SCL3',
09:26:51 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
09:26:51 INFO - 'FP_NO_HOST_CHECK': 'NO',
09:26:51 INFO - 'HOMEDRIVE': 'C:',
09:26:51 INFO - 'HOMEPATH': '\\Users\\cltbld',
09:26:51 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
09:26:51 INFO - 'KTS_VERSION': '1.19c',
09:26:51 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
09:26:51 INFO - 'LOGONSERVER': '\\\\T-W732-IX-035',
09:26:51 INFO - 'MONDIR': 'C:\\Monitor_config\\',
09:26:51 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
09:26:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
09:26:51 INFO - 'MOZ_NO_REMOTE': '1',
09:26:51 INFO - 'NO_EM_RESTART': '1',
09:26:51 INFO - 'NUMBER_OF_PROCESSORS': '8',
09:26:51 INFO - 'OS': 'Windows_NT',
09:26:51 INFO - 'OURDRIVE': 'C:',
09:26:51 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
09:26:51 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
09:26:51 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
09:26:51 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
09:26:51 INFO - 'PROCESSOR_LEVEL': '6',
09:26:51 INFO - 'PROCESSOR_REVISION': '1e05',
09:26:51 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
09:26:51 INFO - 'PROGRAMFILES': 'C:\\Program Files',
09:26:51 INFO - 'PROMPT': '$P$G',
09:26:51 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
09:26:51 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
09:26:51 INFO - 'PUBLIC': 'C:\\Users\\Public',
09:26:51 INFO - 'PWD': 'C:\\slave\\test',
09:26:51 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
09:26:51 INFO - 'SLAVEDIR': 'C:\\slave\\',
09:26:51 INFO - 'SYSTEMDRIVE': 'C:',
09:26:51 INFO - 'SYSTEMROOT': 'C:\\windows',
09:26:51 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:26:51 INFO - 'TEST1': 'testie',
09:26:51 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:26:51 INFO - 'USERDOMAIN': 'T-W732-IX-035',
09:26:51 INFO - 'USERNAME': 'cltbld',
09:26:51 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
09:26:51 INFO - 'WINDIR': 'C:\\windows',
09:26:51 INFO - 'WINDOWS_TRACING_FLAGS': '3',
09:26:51 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
09:26:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
09:26:52 INFO - Ignoring indexes: https://pypi.python.org/simple/
09:26:52 INFO - Downloading/unpacking mozsystemmonitor==0.3
09:26:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
09:26:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
09:26:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz
09:26:53 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor
09:26:53 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)
09:26:53 INFO - Installing collected packages: mozsystemmonitor
09:26:53 INFO - Running setup.py install for mozsystemmonitor
09:26:53 INFO - Successfully installed mozsystemmonitor
09:26:53 INFO - Cleaning up...
09:26:53 INFO - Return code: 0
09:26:53 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv
09:26:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
09:26:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
09:26:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
09:26:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
09:26:53 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01840640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018E7E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017EB228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018F4E90>, '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 0x0130A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F6E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-035', 'USERDOMAIN': 'T-W732-IX-035', '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-035', '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
09:26:53 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in C:\slave\test\build
09:26:53 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1
09:26:53 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
09:26:53 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
09:26:53 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
09:26:53 INFO - 'COMPUTERNAME': 'T-W732-IX-035',
09:26:53 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
09:26:53 INFO - 'CYGWINBASE': 'C:\\cygwin',
09:26:53 INFO - 'DCLOCATION': 'SCL3',
09:26:53 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
09:26:53 INFO - 'FP_NO_HOST_CHECK': 'NO',
09:26:53 INFO - 'HOMEDRIVE': 'C:',
09:26:53 INFO - 'HOMEPATH': '\\Users\\cltbld',
09:26:53 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
09:26:53 INFO - 'KTS_VERSION': '1.19c',
09:26:53 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
09:26:53 INFO - 'LOGONSERVER': '\\\\T-W732-IX-035',
09:26:53 INFO - 'MONDIR': 'C:\\Monitor_config\\',
09:26:53 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
09:26:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
09:26:53 INFO - 'MOZ_NO_REMOTE': '1',
09:26:53 INFO - 'NO_EM_RESTART': '1',
09:26:53 INFO - 'NUMBER_OF_PROCESSORS': '8',
09:26:53 INFO - 'OS': 'Windows_NT',
09:26:53 INFO - 'OURDRIVE': 'C:',
09:26:53 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
09:26:53 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
09:26:53 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
09:26:53 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
09:26:53 INFO - 'PROCESSOR_LEVEL': '6',
09:26:53 INFO - 'PROCESSOR_REVISION': '1e05',
09:26:53 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
09:26:53 INFO - 'PROGRAMFILES': 'C:\\Program Files',
09:26:53 INFO - 'PROMPT': '$P$G',
09:26:53 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
09:26:53 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
09:26:53 INFO - 'PUBLIC': 'C:\\Users\\Public',
09:26:53 INFO - 'PWD': 'C:\\slave\\test',
09:26:53 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
09:26:53 INFO - 'SLAVEDIR': 'C:\\slave\\',
09:26:53 INFO - 'SYSTEMDRIVE': 'C:',
09:26:53 INFO - 'SYSTEMROOT': 'C:\\windows',
09:26:53 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:26:53 INFO - 'TEST1': 'testie',
09:26:53 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:26:53 INFO - 'USERDOMAIN': 'T-W732-IX-035',
09:26:53 INFO - 'USERNAME': 'cltbld',
09:26:53 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
09:26:53 INFO - 'WINDIR': 'C:\\windows',
09:26:53 INFO - 'WINDOWS_TRACING_FLAGS': '3',
09:26:53 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
09:26:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
09:26:54 INFO - Ignoring indexes: https://pypi.python.org/simple/
09:26:54 INFO - Downloading/unpacking jsonschema==2.5.1
09:26: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
09:26: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
09:26:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl
09:26:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1)
09:26:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
09:26:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
09:26:55 INFO - Downloading functools32-3.2.3-2.tar.gz
09:26:55 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32
09:26:55 INFO - warning: no files found matching '*.txt'
09:26:55 INFO - no previously-included directories found matching 'build'
09:26:55 INFO - no previously-included directories found matching 'dist'
09:26:55 INFO - no previously-included directories found matching '.git*'
09:26:55 INFO - Installing collected packages: jsonschema, functools32
09:26:55 INFO - Running setup.py install for functools32
09:26:55 INFO - warning: no files found matching '*.txt'
09:26:55 INFO - no previously-included directories found matching 'build'
09:26:55 INFO - no previously-included directories found matching 'dist'
09:26:55 INFO - no previously-included directories found matching '.git*'
09:26:55 INFO - Successfully installed jsonschema functools32
09:26:55 INFO - Cleaning up...
09:26:56 INFO - Return code: 0
09:26:56 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv
09:26:56 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')]}
09:26:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
09:26:56 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')]}
09:26:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
09:26:56 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 0x01840640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018E7E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017EB228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018F4E90>, '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 0x0130A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F6E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-035', 'USERDOMAIN': 'T-W732-IX-035', '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-035', '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
09:26:56 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
09:26:56 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
09:26:56 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
09:26:56 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
09:26:56 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
09:26:56 INFO - 'COMPUTERNAME': 'T-W732-IX-035',
09:26:56 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
09:26:56 INFO - 'CYGWINBASE': 'C:\\cygwin',
09:26:56 INFO - 'DCLOCATION': 'SCL3',
09:26:56 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
09:26:56 INFO - 'FP_NO_HOST_CHECK': 'NO',
09:26:56 INFO - 'HOMEDRIVE': 'C:',
09:26:56 INFO - 'HOMEPATH': '\\Users\\cltbld',
09:26:56 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
09:26:56 INFO - 'KTS_VERSION': '1.19c',
09:26:56 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
09:26:56 INFO - 'LOGONSERVER': '\\\\T-W732-IX-035',
09:26:56 INFO - 'MONDIR': 'C:\\Monitor_config\\',
09:26:56 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
09:26:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
09:26:56 INFO - 'MOZ_NO_REMOTE': '1',
09:26:56 INFO - 'NO_EM_RESTART': '1',
09:26:56 INFO - 'NUMBER_OF_PROCESSORS': '8',
09:26:56 INFO - 'OS': 'Windows_NT',
09:26:56 INFO - 'OURDRIVE': 'C:',
09:26:56 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;',
09:26:56 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
09:26:56 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
09:26:56 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
09:26:56 INFO - 'PROCESSOR_LEVEL': '6',
09:26:56 INFO - 'PROCESSOR_REVISION': '1e05',
09:26:56 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
09:26:56 INFO - 'PROGRAMFILES': 'C:\\Program Files',
09:26:56 INFO - 'PROMPT': '$P$G',
09:26:56 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
09:26:56 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
09:26:56 INFO - 'PUBLIC': 'C:\\Users\\Public',
09:26:56 INFO - 'PWD': 'C:\\slave\\test',
09:26:56 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
09:26:56 INFO - 'SLAVEDIR': 'C:\\slave\\',
09:26:56 INFO - 'SYSTEMDRIVE': 'C:',
09:26:56 INFO - 'SYSTEMROOT': 'C:\\windows',
09:26:56 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:26:56 INFO - 'TEST1': 'testie',
09:26:56 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:26:56 INFO - 'USERDOMAIN': 'T-W732-IX-035',
09:26:56 INFO - 'USERNAME': 'cltbld',
09:26:56 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
09:26:56 INFO - 'WINDIR': 'C:\\windows',
09:26:56 INFO - 'WINDOWS_TRACING_FLAGS': '3',
09:26:56 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
09:26:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
09:26:56 INFO - Ignoring indexes: https://pypi.python.org/simple/
09:26:56 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages
09:26:56 INFO - Cleaning up...
09:26:56 INFO - Return code: 0
09:26:56 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv
09:26:56 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')]}
09:26:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
09:26:56 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')]}
09:26:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
09:26:56 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 0x01840640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018E7E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017EB228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018F4E90>, '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 0x0130A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F6E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-035', 'USERDOMAIN': 'T-W732-IX-035', '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-035', '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
09:26:56 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
09:26:56 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
09:26:56 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
09:26:56 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
09:26:56 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
09:26:56 INFO - 'COMPUTERNAME': 'T-W732-IX-035',
09:26:56 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
09:26:56 INFO - 'CYGWINBASE': 'C:\\cygwin',
09:26:56 INFO - 'DCLOCATION': 'SCL3',
09:26:56 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
09:26:56 INFO - 'FP_NO_HOST_CHECK': 'NO',
09:26:56 INFO - 'HOMEDRIVE': 'C:',
09:26:56 INFO - 'HOMEPATH': '\\Users\\cltbld',
09:26:56 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
09:26:56 INFO - 'KTS_VERSION': '1.19c',
09:26:56 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
09:26:56 INFO - 'LOGONSERVER': '\\\\T-W732-IX-035',
09:26:56 INFO - 'MONDIR': 'C:\\Monitor_config\\',
09:26:56 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
09:26:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
09:26:56 INFO - 'MOZ_NO_REMOTE': '1',
09:26:56 INFO - 'NO_EM_RESTART': '1',
09:26:56 INFO - 'NUMBER_OF_PROCESSORS': '8',
09:26:56 INFO - 'OS': 'Windows_NT',
09:26:56 INFO - 'OURDRIVE': 'C:',
09:26:56 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;',
09:26:56 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
09:26:56 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
09:26:56 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
09:26:56 INFO - 'PROCESSOR_LEVEL': '6',
09:26:56 INFO - 'PROCESSOR_REVISION': '1e05',
09:26:56 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
09:26:56 INFO - 'PROGRAMFILES': 'C:\\Program Files',
09:26:56 INFO - 'PROMPT': '$P$G',
09:26:56 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
09:26:56 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
09:26:56 INFO - 'PUBLIC': 'C:\\Users\\Public',
09:26:56 INFO - 'PWD': 'C:\\slave\\test',
09:26:56 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
09:26:56 INFO - 'SLAVEDIR': 'C:\\slave\\',
09:26:56 INFO - 'SYSTEMDRIVE': 'C:',
09:26:56 INFO - 'SYSTEMROOT': 'C:\\windows',
09:26:56 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:26:56 INFO - 'TEST1': 'testie',
09:26:56 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:26:56 INFO - 'USERDOMAIN': 'T-W732-IX-035',
09:26:56 INFO - 'USERNAME': 'cltbld',
09:26:56 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
09:26:56 INFO - 'WINDIR': 'C:\\windows',
09:26:56 INFO - 'WINDOWS_TRACING_FLAGS': '3',
09:26:56 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
09:26:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
09:26:56 INFO - Ignoring indexes: https://pypi.python.org/simple/
09:26:56 INFO - Downloading/unpacking blobuploader==1.2.4
09:26: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
09:26: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
09:26:57 INFO - Downloading blobuploader-1.2.4.tar.gz
09:26:57 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader
09:26:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4)
09:26:57 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
09:26:57 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
09:26:58 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests
09:26:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4)
09:26: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
09:26: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
09:26:58 INFO - Downloading docopt-0.6.1.tar.gz
09:26:58 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt
09:26:58 INFO - Installing collected packages: blobuploader, requests, docopt
09:26:58 INFO - Running setup.py install for blobuploader
09:26:58 INFO - Running setup.py install for requests
09:26:59 INFO - Running setup.py install for docopt
09:26:59 INFO - Successfully installed blobuploader requests docopt
09:26:59 INFO - Cleaning up...
09:27:00 INFO - Return code: 0
09:27:00 INFO - Installing None into virtualenv C:\slave\test\build/venv
09:27: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')]}
09:27:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
09:27: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')]}
09:27:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
09:27:00 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 0x01840640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018E7E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017EB228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018F4E90>, '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 0x0130A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F6E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-035', 'USERDOMAIN': 'T-W732-IX-035', '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-035', '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
09:27:01 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
09:27:01 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
09:27:01 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
09:27:01 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
09:27:01 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
09:27:01 INFO - 'COMPUTERNAME': 'T-W732-IX-035',
09:27:01 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
09:27:01 INFO - 'CYGWINBASE': 'C:\\cygwin',
09:27:01 INFO - 'DCLOCATION': 'SCL3',
09:27:01 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
09:27:01 INFO - 'FP_NO_HOST_CHECK': 'NO',
09:27:01 INFO - 'HOMEDRIVE': 'C:',
09:27:01 INFO - 'HOMEPATH': '\\Users\\cltbld',
09:27:01 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
09:27:01 INFO - 'KTS_VERSION': '1.19c',
09:27:01 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
09:27:01 INFO - 'LOGONSERVER': '\\\\T-W732-IX-035',
09:27:01 INFO - 'MONDIR': 'C:\\Monitor_config\\',
09:27:01 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
09:27:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
09:27:01 INFO - 'MOZ_NO_REMOTE': '1',
09:27:01 INFO - 'NO_EM_RESTART': '1',
09:27:01 INFO - 'NUMBER_OF_PROCESSORS': '8',
09:27:01 INFO - 'OS': 'Windows_NT',
09:27:01 INFO - 'OURDRIVE': 'C:',
09:27:01 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;',
09:27:01 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
09:27:01 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
09:27:01 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
09:27:01 INFO - 'PROCESSOR_LEVEL': '6',
09:27:01 INFO - 'PROCESSOR_REVISION': '1e05',
09:27:01 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
09:27:01 INFO - 'PROGRAMFILES': 'C:\\Program Files',
09:27:01 INFO - 'PROMPT': '$P$G',
09:27:01 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
09:27:01 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
09:27:01 INFO - 'PUBLIC': 'C:\\Users\\Public',
09:27:01 INFO - 'PWD': 'C:\\slave\\test',
09:27:01 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
09:27:01 INFO - 'SLAVEDIR': 'C:\\slave\\',
09:27:01 INFO - 'SYSTEMDRIVE': 'C:',
09:27:01 INFO - 'SYSTEMROOT': 'C:\\windows',
09:27:01 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:27:01 INFO - 'TEST1': 'testie',
09:27:01 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:27:01 INFO - 'USERDOMAIN': 'T-W732-IX-035',
09:27:01 INFO - 'USERNAME': 'cltbld',
09:27:01 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
09:27:01 INFO - 'WINDIR': 'C:\\windows',
09:27:01 INFO - 'WINDOWS_TRACING_FLAGS': '3',
09:27:01 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
09:27:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
09:27:01 INFO - Ignoring indexes: https://pypi.python.org/simple/
09:27:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
09:27:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-5wrla_-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
09:27:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
09:27:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-_czc19-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
09:27:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
09:27:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vsfwta-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
09:27:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
09:27:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-b1p6o9-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
09:27:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
09:27:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fiydue-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
09:27:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
09:27:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vtwh7i-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
09:27:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
09:27:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bbvo7w-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
09:27:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
09:27:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-npmi31-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
09:27:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
09:27:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-7wbpu6-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
09:27:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
09:27:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-0fpzsl-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
09:27:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
09:27:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-k3xzv9-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
09:27:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
09:27:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xrdzzw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
09:27:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
09:27:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zp7oz5-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
09:27:03 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
09:27:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-_upydt-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
09:27:03 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
09:27:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-is_int-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
09:27:03 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
09:27:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dpawya-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
09:27:03 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
09:27:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-v7a8mf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
09:27:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
09:27:03 INFO - Running setup.py install for manifestparser
09:27:03 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts
09:27:03 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts
09:27:04 INFO - Running setup.py install for mozcrash
09:27:04 INFO - Running setup.py install for mozdebug
09:27:04 INFO - Running setup.py install for mozdevice
09:27:05 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts
09:27:05 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts
09:27:05 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts
09:27:05 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts
09:27:05 INFO - Running setup.py install for mozfile
09:27:05 INFO - Running setup.py install for mozhttpd
09:27:06 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts
09:27:06 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts
09:27:06 INFO - Running setup.py install for mozinfo
09:27:06 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts
09:27:06 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts
09:27:06 INFO - Running setup.py install for mozInstall
09:27:06 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts
09:27:06 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts
09:27:06 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts
09:27:06 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts
09:27:06 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts
09:27:06 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts
09:27:06 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts
09:27:06 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts
09:27:07 INFO - Running setup.py install for mozleak
09:27:08 INFO - Running setup.py install for mozlog
09:27:08 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts
09:27:08 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts
09:27:09 INFO - Running setup.py install for moznetwork
09:27:09 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts
09:27:09 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts
09:27:10 INFO - Running setup.py install for mozprocess
09:27:10 INFO - Running setup.py install for mozprofile
09:27:10 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts
09:27:10 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts
09:27:10 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts
09:27:10 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts
09:27:10 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts
09:27:10 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts
09:27:11 INFO - Running setup.py install for mozrunner
09:27:11 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts
09:27:11 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts
09:27:12 INFO - Running setup.py install for mozscreenshot
09:27:12 INFO - Running setup.py install for moztest
09:27:13 INFO - Running setup.py install for mozversion
09:27:13 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts
09:27:13 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts
09:27:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion
09:27:13 INFO - Cleaning up...
09:27:13 INFO - Return code: 0
09:27:13 INFO - Installing None into virtualenv C:\slave\test\build/venv
09:27:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
09:27:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
09:27:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
09:27:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
09:27:13 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 0x01840640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018E7E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017EB228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018F4E90>, '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 0x0130A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F6E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-035', 'USERDOMAIN': 'T-W732-IX-035', '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-035', '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
09:27:13 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
09:27:13 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
09:27:13 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
09:27:13 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
09:27:13 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
09:27:13 INFO - 'COMPUTERNAME': 'T-W732-IX-035',
09:27:13 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
09:27:13 INFO - 'CYGWINBASE': 'C:\\cygwin',
09:27:13 INFO - 'DCLOCATION': 'SCL3',
09:27:13 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
09:27:13 INFO - 'FP_NO_HOST_CHECK': 'NO',
09:27:13 INFO - 'HOMEDRIVE': 'C:',
09:27:13 INFO - 'HOMEPATH': '\\Users\\cltbld',
09:27:13 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
09:27:13 INFO - 'KTS_VERSION': '1.19c',
09:27:13 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
09:27:13 INFO - 'LOGONSERVER': '\\\\T-W732-IX-035',
09:27:13 INFO - 'MONDIR': 'C:\\Monitor_config\\',
09:27:13 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
09:27:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
09:27:13 INFO - 'MOZ_NO_REMOTE': '1',
09:27:13 INFO - 'NO_EM_RESTART': '1',
09:27:13 INFO - 'NUMBER_OF_PROCESSORS': '8',
09:27:13 INFO - 'OS': 'Windows_NT',
09:27:13 INFO - 'OURDRIVE': 'C:',
09:27:13 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
09:27:13 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
09:27:13 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
09:27:13 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
09:27:13 INFO - 'PROCESSOR_LEVEL': '6',
09:27:13 INFO - 'PROCESSOR_REVISION': '1e05',
09:27:13 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
09:27:13 INFO - 'PROGRAMFILES': 'C:\\Program Files',
09:27:13 INFO - 'PROMPT': '$P$G',
09:27:13 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
09:27:13 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
09:27:13 INFO - 'PUBLIC': 'C:\\Users\\Public',
09:27:13 INFO - 'PWD': 'C:\\slave\\test',
09:27:13 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
09:27:13 INFO - 'SLAVEDIR': 'C:\\slave\\',
09:27:13 INFO - 'SYSTEMDRIVE': 'C:',
09:27:13 INFO - 'SYSTEMROOT': 'C:\\windows',
09:27:13 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:27:13 INFO - 'TEST1': 'testie',
09:27:13 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:27:13 INFO - 'USERDOMAIN': 'T-W732-IX-035',
09:27:13 INFO - 'USERNAME': 'cltbld',
09:27:13 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
09:27:13 INFO - 'WINDIR': 'C:\\windows',
09:27:13 INFO - 'WINDOWS_TRACING_FLAGS': '3',
09:27:13 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
09:27:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
09:27:14 INFO - Ignoring indexes: https://pypi.python.org/simple/
09:27:14 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
09:27:14 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-6ddaab-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
09:27:14 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))
09:27:14 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
09:27:14 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vpicta-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
09:27:14 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))
09:27:14 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
09:27:14 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zu1v1z-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
09:27:14 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))
09:27:14 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
09:27:14 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-epue3o-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
09:27:14 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))
09:27:14 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
09:27:14 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lblkjq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
09:27:15 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))
09:27:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
09:27:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-uw12qb-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
09:27:15 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))
09:27:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
09:27:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-a1_tla-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
09:27:15 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))
09:27:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
09:27:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jtgbp1-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
09:27:15 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))
09:27:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
09:27:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-co7nhr-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
09:27:15 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))
09:27:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
09:27:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dzusxv-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
09:27:15 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))
09:27:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
09:27:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-uotfo8-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
09:27:15 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))
09:27:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
09:27:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-g_ndwi-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
09:27:16 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))
09:27:16 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
09:27:16 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-20lnho-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
09:27:16 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))
09:27:16 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
09:27:16 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cajoj3-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
09:27:16 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))
09:27:16 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
09:27:16 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dbpkna-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
09:27:16 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))
09:27:16 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
09:27:16 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ws205w-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
09:27:16 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))
09:27:16 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
09:27:16 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ysjyr0-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
09:27:16 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))
09:27:16 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))
09:27:16 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))
09:27:16 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))
09:27:16 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))
09:27:16 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))
09:27:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10))
09:27:16 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
09:27:16 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
09:27:17 INFO - Downloading blessings-1.6.tar.gz
09:27:17 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings
09:27:19 INFO - Installing collected packages: blessings
09:27:19 INFO - Running setup.py install for blessings
09:27:19 INFO - Successfully installed blessings
09:27:19 INFO - Cleaning up...
09:27:22 INFO - Return code: 0
09:27:22 INFO - Done creating virtualenv C:\slave\test\build/venv.
09:27:22 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
09:27:22 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
09:27:23 INFO - Reading from file tmpfile_stdout
09:27:23 INFO - Using _rmtree_windows ...
09:27:23 INFO - Using _rmtree_windows ...
09:27:23 INFO - Current package versions:
09:27:23 INFO - blessings == 1.6
09:27:23 INFO - blobuploader == 1.2.4
09:27:23 INFO - docopt == 0.6.1
09:27:23 INFO - functools32 == 3.2.3-2
09:27:23 INFO - jsonschema == 2.5.1
09:27:23 INFO - manifestparser == 1.1
09:27:23 INFO - mozInstall == 1.12
09:27:23 INFO - mozcrash == 0.17
09:27:23 INFO - mozdebug == 0.1
09:27:23 INFO - mozdevice == 0.48
09:27:23 INFO - mozfile == 1.2
09:27:23 INFO - mozhttpd == 0.7
09:27:23 INFO - mozinfo == 0.9
09:27:23 INFO - mozleak == 0.1
09:27:23 INFO - mozlog == 3.4
09:27:23 INFO - moznetwork == 0.27
09:27:23 INFO - mozprocess == 0.23
09:27:23 INFO - mozprofile == 0.28
09:27:23 INFO - mozrunner == 6.12
09:27:23 INFO - mozscreenshot == 0.1
09:27:23 INFO - mozsystemmonitor == 0.3
09:27:23 INFO - moztest == 0.7
09:27:23 INFO - mozversion == 1.4
09:27:23 INFO - psutil == 3.1.1
09:27:23 INFO - requests == 1.2.3
09:27:23 INFO - Installing None into virtualenv C:\slave\test\build/venv
09:27:23 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')]}
09:27:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
09:27:23 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')]}
09:27:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
09:27:23 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 0x01840640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018E7E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017EB228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018F4E90>, '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 0x0130A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F6E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-035', 'USERDOMAIN': 'T-W732-IX-035', '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-035', '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
09:27:23 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
09:27:23 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
09:27:23 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
09:27:23 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
09:27:23 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
09:27:23 INFO - 'COMPUTERNAME': 'T-W732-IX-035',
09:27:23 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
09:27:23 INFO - 'CYGWINBASE': 'C:\\cygwin',
09:27:23 INFO - 'DCLOCATION': 'SCL3',
09:27:23 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
09:27:23 INFO - 'FP_NO_HOST_CHECK': 'NO',
09:27:23 INFO - 'HOMEDRIVE': 'C:',
09:27:23 INFO - 'HOMEPATH': '\\Users\\cltbld',
09:27:23 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
09:27:23 INFO - 'KTS_VERSION': '1.19c',
09:27:23 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
09:27:23 INFO - 'LOGONSERVER': '\\\\T-W732-IX-035',
09:27:23 INFO - 'MONDIR': 'C:\\Monitor_config\\',
09:27:23 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
09:27:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
09:27:23 INFO - 'MOZ_NO_REMOTE': '1',
09:27:23 INFO - 'NO_EM_RESTART': '1',
09:27:23 INFO - 'NUMBER_OF_PROCESSORS': '8',
09:27:23 INFO - 'OS': 'Windows_NT',
09:27:23 INFO - 'OURDRIVE': 'C:',
09:27:23 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
09:27:23 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
09:27:23 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
09:27:23 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
09:27:23 INFO - 'PROCESSOR_LEVEL': '6',
09:27:23 INFO - 'PROCESSOR_REVISION': '1e05',
09:27:23 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
09:27:23 INFO - 'PROGRAMFILES': 'C:\\Program Files',
09:27:23 INFO - 'PROMPT': '$P$G',
09:27:23 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
09:27:23 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
09:27:23 INFO - 'PUBLIC': 'C:\\Users\\Public',
09:27:23 INFO - 'PWD': 'C:\\slave\\test',
09:27:23 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
09:27:23 INFO - 'SLAVEDIR': 'C:\\slave\\',
09:27:23 INFO - 'SYSTEMDRIVE': 'C:',
09:27:23 INFO - 'SYSTEMROOT': 'C:\\windows',
09:27:23 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:27:23 INFO - 'TEST1': 'testie',
09:27:23 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:27:23 INFO - 'USERDOMAIN': 'T-W732-IX-035',
09:27:23 INFO - 'USERNAME': 'cltbld',
09:27:23 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
09:27:23 INFO - 'WINDIR': 'C:\\windows',
09:27:23 INFO - 'WINDOWS_TRACING_FLAGS': '3',
09:27:23 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
09:27:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
09:27:23 INFO - Ignoring indexes: https://pypi.python.org/simple/
09:27:23 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))
09:27:23 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))
09:27:23 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))
09:27:23 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))
09:27:23 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))
09:27:23 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))
09:27:23 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))
09:27:23 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))
09:27:23 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))
09:27:23 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))
09:27:23 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))
09:27:23 INFO - Cleaning up...
09:27:23 INFO - Return code: 0
09:27:23 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv
09:27:23 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')]}
09:27:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
09:27:23 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')]}
09:27:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
09:27:23 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 0x01840640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018E7E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017EB228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018F4E90>, '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 0x0130A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F6E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-035', 'USERDOMAIN': 'T-W732-IX-035', '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-035', '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
09:27:23 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
09:27:23 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
09:27:23 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
09:27:23 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
09:27:23 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
09:27:23 INFO - 'COMPUTERNAME': 'T-W732-IX-035',
09:27:23 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
09:27:23 INFO - 'CYGWINBASE': 'C:\\cygwin',
09:27:23 INFO - 'DCLOCATION': 'SCL3',
09:27:23 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
09:27:23 INFO - 'FP_NO_HOST_CHECK': 'NO',
09:27:23 INFO - 'HOMEDRIVE': 'C:',
09:27:23 INFO - 'HOMEPATH': '\\Users\\cltbld',
09:27:23 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
09:27:23 INFO - 'KTS_VERSION': '1.19c',
09:27:23 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
09:27:23 INFO - 'LOGONSERVER': '\\\\T-W732-IX-035',
09:27:23 INFO - 'MONDIR': 'C:\\Monitor_config\\',
09:27:23 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
09:27:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
09:27:23 INFO - 'MOZ_NO_REMOTE': '1',
09:27:23 INFO - 'NO_EM_RESTART': '1',
09:27:23 INFO - 'NUMBER_OF_PROCESSORS': '8',
09:27:23 INFO - 'OS': 'Windows_NT',
09:27:23 INFO - 'OURDRIVE': 'C:',
09:27:23 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
09:27:23 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
09:27:23 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
09:27:23 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
09:27:23 INFO - 'PROCESSOR_LEVEL': '6',
09:27:23 INFO - 'PROCESSOR_REVISION': '1e05',
09:27:23 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
09:27:23 INFO - 'PROGRAMFILES': 'C:\\Program Files',
09:27:23 INFO - 'PROMPT': '$P$G',
09:27:23 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
09:27:23 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
09:27:23 INFO - 'PUBLIC': 'C:\\Users\\Public',
09:27:23 INFO - 'PWD': 'C:\\slave\\test',
09:27:23 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
09:27:23 INFO - 'SLAVEDIR': 'C:\\slave\\',
09:27:23 INFO - 'SYSTEMDRIVE': 'C:',
09:27:23 INFO - 'SYSTEMROOT': 'C:\\windows',
09:27:23 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:27:23 INFO - 'TEST1': 'testie',
09:27:23 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:27:23 INFO - 'USERDOMAIN': 'T-W732-IX-035',
09:27:23 INFO - 'USERNAME': 'cltbld',
09:27:23 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
09:27:23 INFO - 'WINDIR': 'C:\\windows',
09:27:23 INFO - 'WINDOWS_TRACING_FLAGS': '3',
09:27:23 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
09:27:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
09:27:23 INFO - Ignoring indexes: https://pypi.python.org/simple/
09:27:23 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages
09:27:23 INFO - Cleaning up...
09:27:24 INFO - Return code: 0
09:27:24 INFO - Running post-action listener: _resource_record_post_action
09:27:24 INFO - Running post-action listener: _start_resource_monitoring
09:27:24 INFO - Starting resource monitoring.
09:27:24 INFO - [mozharness: 2016-09-27 16:27:24.192000Z] Finished create-virtualenv step (success)
09:27:24 INFO - [mozharness: 2016-09-27 16:27:24.192000Z] Running install step.
09:27:24 INFO - Running pre-action listener: _resource_record_pre_action
09:27:24 INFO - Running main action method: install
09:27:24 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
09:27:24 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
09:27:24 INFO - Reading from file tmpfile_stdout
09:27:24 INFO - Using _rmtree_windows ...
09:27:24 INFO - Using _rmtree_windows ...
09:27:24 INFO - Detecting whether we're running mozinstall >=1.0...
09:27:24 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h']
09:27:24 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h
09:27:24 INFO - Reading from file tmpfile_stdout
09:27:24 INFO - Output received:
09:27:24 INFO - Usage: mozinstall-script.py [options] installer
09:27:24 INFO - Options:
09:27:24 INFO - -h, --help show this help message and exit
09:27:24 INFO - -d DEST, --destination=DEST
09:27:24 INFO - Directory to install application into. [default:
09:27:24 INFO - "C:\slave\test"]
09:27:24 INFO - --app=APP Application being installed. [default: firefox]
09:27:24 INFO - Using _rmtree_windows ...
09:27:24 INFO - Using _rmtree_windows ...
09:27:24 INFO - mkdir: C:\slave\test\build\application
09:27:24 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']
09:27:24 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
09:27:29 INFO - Reading from file tmpfile_stdout
09:27:29 INFO - Output received:
09:27:29 INFO - C:\slave\test\build\application\firefox\firefox.exe
09:27:29 INFO - Using _rmtree_windows ...
09:27:29 INFO - Using _rmtree_windows ...
09:27:29 INFO - Running post-action listener: _resource_record_post_action
09:27:29 INFO - [mozharness: 2016-09-27 16:27:29.087000Z] Finished install step (success)
09:27:29 INFO - [mozharness: 2016-09-27 16:27:29.087000Z] Running run-tests step.
09:27:29 INFO - Running pre-action listener: _resource_record_pre_action
09:27:29 INFO - Running main action method: run_tests
09:27:29 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version']
09:27:29 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version
09:27:29 INFO - Python 2.7.3
09:27:29 INFO - Return code: 0
09:27:29 INFO - grabbing minidump binary from tooltool
09:27:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
09:27:29 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 0x018F4E90>, '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 0x0130A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F6E520>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1
09:27:29 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
09:27:29 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
09:27:29 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
09:27:29 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'...
09:27:31 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmpw5vnp2
09:27:31 INFO - INFO - File integrity verified, renaming tmpw5vnp2 to win32-minidump_stackwalk.exe
09:27:31 INFO - Return code: 0
09:27:31 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755
09:27:31 INFO - mkdir: C:\slave\test\build\blobber_upload_dir
09:27:31 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir
09:27:31 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe
09:27:31 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir
09:27:31 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Q1XRv_39T_i19CV2BUuI_A/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-035', '--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
09:27:31 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --branchName Autoland-Non-PGO --suite dromaeojs --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/Q1XRv_39T_i19CV2BUuI_A/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-035 --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
09:27:31 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
09:27:31 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
09:27:31 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
09:27:31 INFO - 'COMPUTERNAME': 'T-W732-IX-035',
09:27:31 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
09:27:31 INFO - 'CYGWINBASE': 'C:\\cygwin',
09:27:31 INFO - 'DCLOCATION': 'SCL3',
09:27:31 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
09:27:31 INFO - 'FP_NO_HOST_CHECK': 'NO',
09:27:31 INFO - 'HOMEDRIVE': 'C:',
09:27:31 INFO - 'HOMEPATH': '\\Users\\cltbld',
09:27:31 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
09:27:31 INFO - 'KTS_VERSION': '1.19c',
09:27:31 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
09:27:31 INFO - 'LOGONSERVER': '\\\\T-W732-IX-035',
09:27:31 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir',
09:27:31 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe',
09:27:31 INFO - 'MONDIR': 'C:\\Monitor_config\\',
09:27:31 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
09:27:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
09:27:31 INFO - 'MOZ_NO_REMOTE': '1',
09:27:31 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir',
09:27:31 INFO - 'NO_EM_RESTART': '1',
09:27:31 INFO - 'NUMBER_OF_PROCESSORS': '8',
09:27:31 INFO - 'OS': 'Windows_NT',
09:27:31 INFO - 'OURDRIVE': 'C:',
09:27:31 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;',
09:27:31 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
09:27:31 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
09:27:31 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
09:27:31 INFO - 'PROCESSOR_LEVEL': '6',
09:27:31 INFO - 'PROCESSOR_REVISION': '1e05',
09:27:31 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
09:27:31 INFO - 'PROGRAMFILES': 'C:\\Program Files',
09:27:31 INFO - 'PROMPT': '$P$G',
09:27:31 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
09:27:31 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
09:27:31 INFO - 'PUBLIC': 'C:\\Users\\Public',
09:27:31 INFO - 'PWD': 'C:\\slave\\test',
09:27:31 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos',
09:27:31 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
09:27:31 INFO - 'SLAVEDIR': 'C:\\slave\\',
09:27:31 INFO - 'SYSTEMDRIVE': 'C:',
09:27:31 INFO - 'SYSTEMROOT': 'C:\\windows',
09:27:31 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:27:31 INFO - 'TEST1': 'testie',
09:27:31 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:27:31 INFO - 'USERDOMAIN': 'T-W732-IX-035',
09:27:31 INFO - 'USERNAME': 'cltbld',
09:27:31 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
09:27:31 INFO - 'WINDIR': 'C:\\windows',
09:27:31 INFO - 'WINDOWS_TRACING_FLAGS': '3',
09:27:31 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
09:27:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
09:27:31 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Q1XRv_39T_i19CV2BUuI_A/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-035', '--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
09:27:33 INFO - mozversion application_buildid: 20160927070154
09:27:33 INFO - mozversion application_changeset: 8009d19e29e0c4312cbc5f6dca6c32697dafd46f
09:27:33 INFO - mozversion application_display_name: Nightly
09:27:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
09:27:33 INFO - mozversion application_name: Firefox
09:27:33 INFO - mozversion application_remotingname: firefox
09:27:33 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland
09:27:33 INFO - mozversion application_vendor: Mozilla
09:27:33 INFO - mozversion application_version: 52.0a1
09:27:33 INFO - mozversion platform_buildid: 20160927070154
09:27:33 INFO - mozversion platform_changeset: 8009d19e29e0c4312cbc5f6dca6c32697dafd46f
09:27:33 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland
09:27:33 INFO - mozversion platform_version: 52.0a1
09:27:33 INFO - using testdate: 1474993653
09:27:33 INFO - actual date: 1474993653
09:27:33 INFO - starting webserver on 'localhost:49889'
09:27:33 INFO - SUITE-START | Running 2 tests
09:27:33 INFO - TEST-START | dromaeo_css
09:27:33 INFO - operating with platform_type : w7_
09:27:33 INFO - Initialising browser for dromaeo_css test...
09:27:33 INFO - TEST-INFO | started process 3684 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmptblmau\profile http://localhost:49889/getInfo.html)
09:27:44 INFO - PROCESS | 3684 | __metrics Screen width/height:1600/1200
09:27:44 INFO - PROCESS | 3684 | colorDepth:24
09:27:44 INFO - PROCESS | 3684 | Browser inner width/height: 1010/674
09:27:44 INFO - PROCESS | 3684 | __metrics
09:27:45 INFO - PROCESS | 3684 | 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"
09:27:45 INFO - PROCESS | 3684 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
09:27:46 INFO - TEST-INFO | 3684: exit 0
09:27:46 INFO - Browser initialized.
09:27:46 INFO - Running cycle 1/1 for dromaeo_css test...
09:27:46 INFO - TEST-INFO | started process 2228 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmptblmau\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1)
09:27:47 INFO - PROCESS | 2228 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0
09:27:58 INFO - PROCESS | 2228 | RSS: Main: 139788288
09:27:58 INFO - PROCESS | 2228 |
09:28:49 INFO - PROCESS | 2228 | 1474993729961 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]
09:31:03 INFO - PROCESS | 2228 | Cycle 1(1): loaded http://localhost:49889/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49889/tests/dromaeo/cssquery-ext.html)
09:31:04 INFO - PROCESS | 2228 | RSS: Main: 150761472
09:31:04 INFO - PROCESS | 2228 |
09:34:07 INFO - PROCESS | 2228 | Cycle 1(1): loaded http://localhost:49889/tests/dromaeo/cssquery-ext.html (next: http://localhost:49889/tests/dromaeo/cssquery-jquery.html)
09:34:08 INFO - PROCESS | 2228 | RSS: Main: 204124160
09:34:08 INFO - PROCESS | 2228 |
09:37:10 INFO - PROCESS | 2228 | Cycle 1(1): loaded http://localhost:49889/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49889/tests/dromaeo/cssquery-mootools.html)
09:37:11 INFO - PROCESS | 2228 | RSS: Main: 154804224
09:37:11 INFO - PROCESS | 2228 |
09:40:13 INFO - PROCESS | 2228 | Cycle 1(1): loaded http://localhost:49889/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49889/tests/dromaeo/cssquery-prototype.html)
09:40:14 INFO - PROCESS | 2228 | RSS: Main: 142315520
09:40:14 INFO - PROCESS | 2228 |
09:43:17 INFO - PROCESS | 2228 | Cycle 1(1): loaded http://localhost:49889/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49889/tests/dromaeo/cssquery-yui.html)
09:43:17 INFO - PROCESS | 2228 | RSS: Main: 144736256
09:43:17 INFO - PROCESS | 2228 |
09:46:20 INFO - PROCESS | 2228 | Cycle 1(1): loaded http://localhost:49889/tests/dromaeo/cssquery-yui.html (next: http://localhost:49889/tests/dromaeo/cssquery-dojo.html)
09:46:20 INFO - PROCESS | 2228 | RSS: Main: 186744832
09:46:20 INFO - PROCESS | 2228 |
09:46:20 INFO - PROCESS | 2228 | __start_tp_report
09:46:20 INFO - PROCESS | 2228 | _x_x_mozilla_page_load
09:46:20 INFO - PROCESS | 2228 | _x_x_mozilla_page_load_details
09:46:20 INFO - PROCESS | 2228 | |i|pagename|runs|
09:46:20 INFO - PROCESS | 2228 | |0;dojo.html;2336.3224664847194;2350.5298940211956;2376.215848769906;2390.330707401928;2401.5317013182434;3841.5582208046085;4006.979965100174;4110.8150133244;4112.6504247138955;4115.279826030448;3862.38201887698;3864.86472973446;4025.933127721149;4026.4161696553983;4118.238125946702;3418.0771191778204;3552.591451983016;3552.626974167714;3587.2825434912993;3631.981840090809;3427.3488037272964;3446.827658617072;3510.2102027043966;3534.0811389038777;3561.5904171020275;3435.656434356557;3462.1690794209335;3543.85824567017;3590.263995880838;3632.0011996700855;3433.6223015468527;3510.526078979356;3538.5930617978747;3591.3715099857423;3595.1191957970464;3387;3525.1715846775987;3529.364714351416;3543.1850674344787;3594.370985077627;3997.72015958883;4049.3116170250833;4068.8982775430554;4072.5927407259333;4078.7552746835286;4165.687573431975;4175.269327867642;4197.307444271692;4227.133437645275;4334.501532323791;4232.830686772556;4263.765492897889;4265.616094551505;4303.548127446636;4322.157179350028;3944.15200731844;3959.148783011666;3996.160806230666;4044.0698639313123;4047.2714911315657;4105.34314509677;4112.321466957978;4153.646940010133;4371.475422949203;4378.978105109454;4212.557681443465;4239.364095385655;4250.766207858597;4259.680523960716;4306.526282109028;4226.344916537942;4276.87169384919;4291.313389857608;4352.912941741148;4362.9965108025335;4108.993296642342;4234.301340278819;4320.200762858881;4327.026419055687;4328.43730315057;4145.440365550612;4181.393697913785;4181.414601955728;4212.473440819897;4223.831046758095;2911.941761164765;3004.083754454912;3016.8642411091555;3028.969710302869;3032.105528868989;2704;2718.5786203138546;2767.3081729567607;2768.349437882096;2769.9723002769715;3907.159960608483;3950.585188555218;3981.1440540283397;4023.939640905389;4073.9592604074173;4247.532771395144;4272.33778764292;4358.455193100863;4366.257736184856;4370.038591509863;3950.545687245946;3968.6825053995613;4033.979830100831;4064.6138616831354;4065.7153999220363;3566.5363502744476;3592.1019745063736;3620.149264922741;3635.4910312556262;3665.8350374233223;4320.222359975109;4325.22146013708;4372.978135109304;4421.712588681726;4431.379606855107;4162.14675991415;4186.769727665008;4239.703220774516;4333.241682705577;4345.152695224528;4319.33050377192;4398.120375924764;4401.141777353385;4415.735055896656;4432.15789000089;4115.814788334472;4127.442795222607;4174.3321068628875;4204.831806727697;4224.640905523065;4233.767142807052;4304.849330273425;4316.84891028825;4328.939409844608;4329.307310830252;3986.1828325193846;4098.446709694273;4111.588841115865;4198.580141985777;4203.1593681264485;3936.2717897189114;4064.146529228895;4096.262672718939;4125.216208920296;4157.397177409319;4067.2068946555137;4092.386142078712;4094.6519545837746;4096.467459230281;4119.011437254977;4065.918681626292;4113.71204015728;4141.461609990682;4204.07510347723;4210.726302790309;4078.7348822326453;4124.072083781125;4159.771212583216;4168.041350489465;4217.557156498524
09:46:20 INFO - PROCESS | 2228 | |1;ext.html;521.5228066319318;521.8825764203053;534.8555889909724;541.3666010767399;568.5110804707953;26345.077922272725;26987.32531686709;28198.15405537832;28316.716832831666;38626;36101.81949090251;36487.4526882097;37099.443508347395;37685.62314376856;38319.23361532768;7282.126144862624;7544.509606875563;7633.313001829834;7672.539647621161;7706.229377062305;6208.286047104573;6593.208814942213;7171;7343.15553711322;7358.264173582653;9511.28665350098;9772.95113524435;9781.706548803548;10018.148457381096;10069.395836249812;9532.904670953305;9591.088846559565;9603.18372938301;9626;9646.180074693659;6355.205599300088;6577.046328282372;9484.430934143973;9548.140667339903;9551.90448095524;32602.510962335346;33673.158171045674;34442.82778586116;36786.264274714624;37063.258734825155;2579.5356835769553;2720.6825572029543;2784.3456787654886;2866.942661146786;3266.7223286020717;26598.202053938414;26750.33124171892;27075;27165.728342716517;27637.894484220607;2990.685977972303;3025.410045041218;3067.5245336972803;3091.8454077296046;3094.7214750672547;215941;216081;218523;218852;219105;47357.28964065542;47635.761821191365;47671;47703;47721.76139119282;41328.79335603363;41856.37215441771;47535;47892.5210747895;49383.50616493861;9728.513574321256;9857.35927164732;9895.60417583303;10001;10020.348677336096;7342.77971660851;7405.444591655647;7413.221611730798;7425.220351862977;7431.739889103964;13012.869871301355;13303.3348332583;14993.400263989537;15493.922530387275;15541.844581554265;5815.79644712433;5842.970785146047;5851.414858514199;5918.348981612019;5922.082077278029;696.4080531548144;816.2164322250587;916.3539704508306;936.3025986869973;957.3155194036298;25514.362140946625;25620.359491012874;25857.699171669763;25900.481990360087;25918.481630367663;868.2359523619175;873.6505397840788;890.9153630405101;928.4939707859232;1018.221060888421;12857.935710321388;13592.38834252461;13634;13656.795148072786;13887.305634718223;306474;308370;308892;309434;309453.16913908353;44067.77966110149;44275.33586996198;44394.77802610966;44629.776851116185;44639.55360446419;933.6452148183782;968.9099762766747;1001.9129244769507;1054.8523206751215;1074.5164675895878;23804.571725696514;25917.74082259153;26238;26326.605100923884;26409;36954.44568331531;37520;37650.058748531505;37659.62340376561;37672.62327376801;227673;232722;236194.81902590377;240113;241644;20865.06107225209;21595.568088637825;21674.566508670054;22572.322830315115;32941.17647058843;37542.812285938395;38099.61900380961;38297.23405531929;38298;38387.80806095952;32909.341813163126;33227.335453291285;33385.83307083449;33395.66604333925;33662.495062573595;32107.795380626096;32285.677143228266;32418.513722293712;32559.511607325436;34041.48937765985;30611.2347191322;31493.8425307872;31706.36587268288;31713.682863171995;31717.13233051893;27747.29518939923;28666.71333286724;29000.12999610015;29300;29988.850055749583;21799.782002179774;22771.88614056919;24196.274111776675;24248.87875560611;24780.752192478565
09:46:20 INFO - PROCESS | 2228 | |2;jquery.html;280.2349585631228;300.15656006621384;304.57055551672147;305.2384301168584;306.62744765110386;10675.893241067586;11760.118381059032;15513.767293490593;15523.301451434685;15789.763153552678;14470.421183152688;15052.24738763063;15151.92424037881;15586.454474093409;15944.920275398606;3980.6616437602765;4648.325992731052;4648.442186937572;4656.371389862376;4667.206574882278;3220.114468521164;3370.3427831572853;4609.953900461002;4670.112678591074;4671.649626278026;5013.949860501385;5045.167547354682;5225.503577160182;5251.632385733;5328.706921119337;5177.76700048495;5320.787168513255;5331.067063263933;5349.170878513836;5452.727363631842;5054.292399064134;5068.619853510964;5138.743062846843;5254.947450525484;5273.499017593323;10784.784304313947;10847.186461015377;12316.261024338568;17878.374256900945;19795.406137815888;1604.7994000749907;1874.2315650583196;2195.9780402195934;2208.381653137124;2263.0834512022493;7968.601569921539;8426.031006434278;8695;8696.652133914698;10658.786824263549;1592.4028489316506;1617.7249867522432;1670.7493875918587;1700.5578549577199;1716.682413753447;62519;63812.68093659548;64791.352086479004;64814.67592662053;65477.672611637114;8587.398882078194;8639.827203455896;8783.341249406192;8896.154865287788;8911.420757650732;10021.498925053718;10593.629222977313;10638.840417393745;10701.678949631523;11685.649430517098;4784.258439941745;4919.942212424275;4944.60443164546;5526.557875369961;5897.115432685046;5495.752691128909;5508.063629183049;5555.333359996826;5638.295213098363;5665.688387138747;3626.8730594429067;3662.505561749183;3685.355062863988;3731.8880433587037;3760.7025576176175;1581.0908727481744;1612.282534272261;1612.9919350403406;1617.8351586857443;1618.5791694159602;736.7863319637245;792.3344390711715;831.7837362285728;870.8171284030298;907.9727608171765;15895;15948.20258987046;16026.919865400832;16101.51695449115;16587.83412165863;734.5188901269677;743.8437928035065;784.4665627373441;786.4337676872642;789.2817536042174;8984.437033508113;9023.954880225558;9027.729168124966;9243.352965292499;9279.582418791171;295372;295459;295788.52105739334;296828;302490;77294;79674.60162699265;80142.39359228848;80232;81821.18178818136;781.355381810009;781.5427974634675;809.9511133082672;813.7355359508065;814.7637185216339;14583.489577864724;14700.926495367456;14949.476768313058;15037.248137592858;15086.24568771579;35693;35962.64037359697;36940.076498087765;37337.81331093327;37490.2501949954;43195;43448.56551434445;45778.39775607971;48297.75851120722;49894.75052624859;6147.815565533041;6420.711068001858;6626.635535045619;6678.599284042973;6714.630695311805;8806;11075.612353567587;11166.44167791591;11201.831972520582;11313;8434.367422443218;9251;9271.165595096474;9498.95250523743;9623.422594644064;9153.130452606993;9317.114874087221;9451.338406311492;9480.241580673392;9581.281403894875;9356;9497.952510237405;9571.952140239255;9656.37233579815;9781.853272201066;6580.65096655175;6614.735410583715;6747.628880411428;6871.244163142151;6979.267176946348;8823.867641985247;9418.199453046294;9443.150116489549;9641.228701703707;9718.465484398426
09:46:20 INFO - PROCESS | 2228 | |3;mootools.html;1155.0531118171955;1191.2733232728037;1238.58507400021;1434.5767998440454;1501.7897494350786;5870.471657550819;6112.755489780404;6598.27418983912;6607.966960165192;6642.070110184566;5563.471470210334;5624.128260119688;6039.852428038669;6160.168377269068;6240.781572644958;1769.9026553539582;1807.8553715702712;1870.710039943811;1881.5484283771932;1881.6330815490985;1807.7288406739028;1838.7517685112473;1843.6128413033211;1849.6485667723089;1863.6179583185483;1608.348618809384;1657.5193193973732;1775.6004898897775;1880.0787614069077;1880.56746948202;1819.3814103204907;1826.5159732670852;1867.6358110168524;1889.102676228794;1901.2965202874886;1741.5994321306043;1752.8685348598906;1774.7870255569414;1792.354752289175;1806.3677712800545;5823.854403639901;5955.970220148914;6090.116933044684;6209.968950155266;6266.404691554295;3855.5373355197275;3928.0376307804486;3936.3308237599676;3986.521617405901;3987.2224916141363;2357.3399448154546;2434.379233295498;2458.0905065126017;2476.7523247675267;2487.4278915849277;763.7785042337715;785.2932360875201;792.6591565626778;811.2212276214841;812.8171161488618;8155.836883262361;8171.305439037689;8206.671733130668;8219.383546233996;8221.630026648816;3384.323135372935;3508.8771892983623;3652.5616925969052;3657.231981283907;3661.9816900915857;7131.714731410685;7178.748743794046;7195.532290401107;7195.640217989079;7199.424046076301;3715.554133503943;3800.847966081326;3861.169848482534;3863.9034024149623;3864.7294689371474;3505.4216054351255;3536.2397084626928;3539.433690609542;3553.987113672591;3557.5197348358156;2822.8729707163225;2998.295400580862;3009.6839831817783;3024.2892920163745;3040.467918114321;979.1823827104448;1006.5369929832359;1010.0808264479289;1013.366645846346;1028.1466382902174;587.436061381079;657.7467674945085;668.355037388922;683.7914436096938;693.5803838677527;3851.1912498375655;3928.6660633846445;4068.5117785865887;4108.993296642282;4154.875353739392;340.98295085246133;341.07228338918105;341.48265377952424;345.2077482178407;347.82956351388145;2690.085370973839;2771.196353057591;2808.4102338508733;2814.2542226310043;2878.6977367376544;7294.197638259787;7520.736774212876;7585.924140758632;7591.127020392725;7679.385649148055;7144.571325720473;7209.639518024078;7516.361109305771;7526.134494533198;7561.470697051263;5587.776488940478;5708.828735137952;5710.600257981984;5930.851728706817;5963.671998040149;4070.898227544335;4072.572379900186;4137.089840235264;4147;4153.646940010133;7155.356017958408;7161.4986950912935;7165.820854478679;7186.7125314986815;7198.676059577437;6996.125484314461;7019.929800701928;7225.747098851515;7330.450216233698;7352.448566357438;5230.843074707765;5380.139177731544;5398.974194902956;5500.064988951808;5561.6663000220115;5686.744096515749;5848.53211743051;5921.2006379138265;5943.227380440515;5963.135345374983;4813.7593120343145;4965.354503914468;5563.58273129525;5571.777128914798;5597.27235459404;5232.633715639869;5337.893242135213;5394.433584473573;5543.805966791143;5587.413321601173;5123.641345105807;5151.639385242997;5236.9738151309;5264.10510213257;5266.815661451831;3328.500724891189;3384.2554637979606;3571.303595798898;3618.9457158143177;3658.707303415667;3591.0842735102383;3629.927401452009;3666.633336666312;3678.6321367863;3687.4653175288895
09:46:20 INFO - PROCESS | 2228 | |4;prototype.html;242.21280837278843;245.8574027064303;246.73106314117615;249.1938578697912;251.19742422958655;4145.6476199523;4378.518362980078;4381.737095774251;4424.823007079713;4425.7565833879125;4236.682248831342;4296.7207131536425;4318.5897339752755;4358.692392282311;4366.847160349388;3157.258044359574;3221.8066915985114;3279.672032796725;3284.881251592715;3285.950710739341;3266.395716792389;3286.457734473821;3292.621348544912;3294.275259442919;3298.208429976812;3075.5694202811624;3158.1946603616047;3169.4611915974338;3225.6935591118922;3227.2093337132437;3213.4376484115187;3247.6752324767335;3256.5115232715048;3262.526933594639;3263.673632636741;2835.744782969522;2837.0779496663667;2872.0809341010886;2875.6549214094443;2876.8417737024456;4257.808398622069;4272;4299.699660681552;4303.548127446605;4304.569543045671;3653.451982202664;3716.312482190803;3808.447775072627;3839.4066462418064;3839.4432807243074;4356.194104090721;4370.091842462122;4461.21928662486;4519.389882365871;4523.5024147343765;3228.741700663965;3229.6931791479774;3232.1273256220925;3237.190702324419;3241.594800649919;4604.539546045402;4624.491305956376;4692.648051396125;4703.15343238217;4717.150912835654;3299.851506682205;3300.6534313897178;3301.752368572367;3311.9172020699675;3314.105191598255;3324.069260607034;3339.3488269787645;3343.164208947763;3343.331333733263;3343.348047130835;3519.7184225261917;3682.4844521766972;3800.258949504875;3829.6744776693736;3829.7127715421457;4387.539308372575;4453.910921781546;4529.614982726505;4533.886652833706;4546;1035.4822588705647;1146.3293973025873;1155.3183621663259;1157.6121999130216;1160.7678464307173;717.436812102497;775.8215610409637;802.5826570183472;804.9879251811227;807.8990126234221;4283.2290187765875;4314.439122914001;4316.352547117957;4323.848665296762;4325.567443255649;4542.545745425431;4587.082583483317;4625.1443482955765;4677.345171675968;4678.157931572349;4273.9742461809565;4312.331588603772;4330.415393921843;4332.241857674894;4339.284018136972;2402.6516155157756;2413.5293617744724;2427.490227052339;2469.9749603914534;2489.95020099597;4719.244920812654;4721.551452611996;4741.407324084489;4742.241241401428;4761.928561075943;4504.774761261924;4630.930536041962;4731.195696731564;4734.171519983989;4747.454042785055;4668.136394766979;4673.415823022122;4716.9292460613115;4720.291956206596;4777.665563410528;4455.821767129279;4605.93091103627;4617.330487079287;4637.837675681335;4665.743384113906;4547.4997750246785;4549.499555049008;4552.658550608651;4557;4560.293154561048;4625;4631.355868666709;4714.175019371528;4739.194336962793;4740.506740376771;3290.1281160492968;3306.4379055561094;3314.254292784104;3316.8009919404913;3320.1865542942132;4432.645388368987;4493.168763778712;4546.636269098531;4568.565986231302;4607.124646317189;4308.612224899774;4325.48094228682;4371.808579736747;4383.912321753611;4433.401490798701;4250.298700714347;4269.466316710411;4403.379988906596;4429.689921705579;4453.997850483615;4316.589923957219;4387.319965405367;4390.1878152541885;4390.64874810008;4392.69251152429;4190.769507676985;4397.582229688174;4504.9099018020115;4504.954950450585;4507.2112380332655;4195.286801243735;4277.65778737694;4371.562843715603;4415.116976604628;4443.289073748314
09:46:20 INFO - PROCESS | 2228 | |5;yui.html;556.2268446858869;576.2336092996313;580.712547289092;600.5885968111843;623.6195920488502;12814.807777883327;13213.009024323179;13281.734365312688;13417.060805743602;13666.590002299921;12730.490780368775;12842.486300547991;12843.100982931199;12871.485140594388;13202.53791117311;643.8969764837627;666.4168852254278;667.7929841749074;668.3083009085598;668.3917634952195;659.0246435275794;659.6306068601574;659.8284446044039;659.8812213801514;659.967001649918;687.656171914043;693.2720643324515;693.8716337477557;695.4957655699628;695.8434352270749;659.6800551732406;680.1192455769765;688.7589343729704;690.6270613868505;692.6848284030754;499.7825945713626;501.3832985427908;501.95482406583585;503.1547001038254;503.61976707585904;12973.870261297361;13322.134061286082;13436.596902092953;13561.864381356258;13563.525276615272;12035.458404371824;12240.938795306056;12365.629031129081;12638.810417843739;12753.298568578724;10418.374897506174;10460.11089057431;10462.633807816766;10581.629642962536;10665.786684266272;3055.175102722275;3163.6519982801874;3170.8256045917465;3186.879191465923;3278.704916557497;49710;59419.40580594225;63135.684321578556;63492;64766;9187.310951678652;9300.162985331217;9391.295652826066;9393.013733558015;9563.569639366246;17436.47690569285;17460.301587936596;17462.30150793954;17489.125543722763;17492.47522574325;5928.851778705481;6134.693265336716;6158.261008678987;6199.814005579839;6327.082573026885;3494.7553671242767;3568.072301201706;3585.5338805955057;3599.1893358828756;3714.7771133732062;2289.255991802671;2491.426971796497;2496.2885577610296;2506.749325067515;2507.448361360498;395.9920801583952;407.5659422714799;409.9282625540578;414.1737234217755;415.48064918851435;476.3022172517279;511.5345036017281;517.5161224255259;522.9111051121318;528.5375296615462;7146.1187214611255;7174.533655312388;7460.925390746023;7512.39900807934;7535.660895259726;465.27649505019343;485.77654279031333;506.95944324453956;533.6931264522915;550.1417788250342;3352.547406100194;3635.2800953515984;3652.4521321801412;3676.246369494247;3828.75113117652;85001;91873.54063229775;96069;96281;96300.51849740847;79958;81474;82466.58766706249;83535.16464835395;83935.58032209922;47799;72397;73821.26178738251;74498.25501744913;75043;6913.60722248976;7138.428925686037;7215.783526494213;7318.487705860539;7469.215732348025;41335.586644133175;44124.77937610292;44252.33621495616;44586.55413445824;44722.55277447184;39812.2037559253;44220.55779442164;61331.693341533006;63919.04121438275;64353;9204.217641500547;9488.573014214238;9714.174295184988;9715.562799674173;9945.850812237677;10278.845817312596;10434.582616695247;10685.50402943604;10900.727481813017;11004.889951100708;9625.181859542017;10103.292769506064;10113.342632728849;10449.425281609585;10582.259241852997;9457.810843782949;9910.0262175261;10012.499375031364;10019.09828115444;10281.640142594973;9734.41593504392;9772.722729725869;9794.046227178784;9889.25830562725;10024.298299118991;8557.61490732906;8606.311495080503;8703.869441958503;8936.704177894038;9242.353035287466;8048.436609437284;8346.374021948257;8352.248297653241;8360.331173505983;8722.91277087221
09:46:20 INFO - PROCESS | 2228 | __end_tp_report
09:46:20 INFO - PROCESS | 2228 | __start_cc_report
09:46:20 INFO - PROCESS | 2228 | _x_x_mozilla_cycle_collect,1271
09:46:20 INFO - PROCESS | 2228 | __end_cc_report
09:46:20 INFO - PROCESS | 2228 | __startTimestamp1474994780615__endTimestamp
09:46:20 INFO - PROCESS | 2228 |
09:46:20 INFO - PROCESS | 2228 | ------- Summary: start -------
09:46:20 INFO - PROCESS | 2228 | Number of tests: 6
09:46:20 INFO - PROCESS | 2228 |
09:46:20 INFO - PROCESS | 2228 | [#0] dojo.html Cycles:165 Average:3926.18 Median:4105.34 stddev:479.57 (11.7%) stddev-sans-first:464.53
09:46:20 INFO - PROCESS | 2228 | Values: 2336.3 2350.5 2376.2 2390.3 2401.5 3841.6 4007.0 4110.8 4112.7 4115.3 3862.4 3864.9 4025.9 4026.4 4118.2 3418.1 3552.6 3552.6 3587.3 3632.0 3427.3 3446.8 3510.2 3534.1 3561.6 3435.7 3462.2 3543.9 3590.3 3632.0 3433.6 3510.5 3538.6 3591.4 3595.1 3387.0 3525.2 3529.4 3543.2 3594.4 3997.7 4049.3 4068.9 4072.6 4078.8 4165.7 4175.3 4197.3 4227.1 4334.5 4232.8 4263.8 4265.6 4303.5 4322.2 3944.2 3959.1 3996.2 4044.1 4047.3 4105.3 4112.3 4153.6 4371.5 4379.0 4212.6 4239.4 4250.8 4259.7 4306.5 4226.3 4276.9 4291.3 4352.9 4363.0 4109.0 4234.3 4320.2 4327.0 4328.4 4145.4 4181.4 4181.4 4212.5 4223.8 2911.9 3004.1 3016.9 3029.0 3032.1 2704.0 2718.6 2767.3 2768.3 2770.0 3907.2 3950.6 3981.1 4023.9 4074.0 4247.5 4272.3 4358.5 4366.3 4370.0 3950.5 3968.7 4034.0 4064.6 4065.7 3566.5 3592.1 3620.1 3635.5 3665.8 4320.2 4325.2 4373.0 4421.7 4431.4 4162.1 4186.8 4239.7 4333.2 4345.2 4319.3 4398.1 4401.1 4415.7 4432.2 4115.8 4127.4 4174.3 4204.8 4224.6 4233.8 4304.8 4316.8 4328.9 4329.3 3986.2 4098.4 4111.6 4198.6 4203.2 3936.3 4064.1 4096.3 4125.2 4157.4 4067.2 4092.4 4094.7 4096.5 4119.0 4065.9 4113.7 4141.5 4204.1 4210.7 4078.7 4124.1 4159.8 4168.0 4217.6
09:46:20 INFO - PROCESS | 2228 |
09:46:20 INFO - PROCESS | 2228 | [#1] ext.html Cycles:180 Average:39775.78 Median:33529.08 stddev:67283.23 (200.7%) stddev-sans-first:67407.42
09:46:20 INFO - PROCESS | 2228 | Values: 521.5 521.9 534.9 541.4 568.5 26345.1 26987.3 28198.2 28316.7 38626.0 36101.8 36487.5 37099.4 37685.6 38319.2 7282.1 7544.5 7633.3 7672.5 7706.2 6208.3 6593.2 7171.0 7343.2 7358.3 9511.3 9773.0 9781.7 10018.1 10069.4 9532.9 9591.1 9603.2 9626.0 9646.2 6355.2 6577.0 9484.4 9548.1 9551.9 32602.5 33673.2 34442.8 36786.3 37063.3 2579.5 2720.7 2784.3 2866.9 3266.7 26598.2 26750.3 27075.0 27165.7 27637.9 2990.7 3025.4 3067.5 3091.8 3094.7 215941.0 216081.0 218523.0 218852.0 219105.0 47357.3 47635.8 47671.0 47703.0 47721.8 41328.8 41856.4 47535.0 47892.5 49383.5 9728.5 9857.4 9895.6 10001.0 10020.3 7342.8 7405.4 7413.2 7425.2 7431.7 13012.9 13303.3 14993.4 15493.9 15541.8 5815.8 5843.0 5851.4 5918.3 5922.1 696.4 816.2 916.4 936.3 957.3 25514.4 25620.4 25857.7 25900.5 25918.5 868.2 873.7 890.9 928.5 1018.2 12857.9 13592.4 13634.0 13656.8 13887.3 306474.0 308370.0 308892.0 309434.0 309453.2 44067.8 44275.3 44394.8 44629.8 44639.6 933.6 968.9 1001.9 1054.9 1074.5 23804.6 25917.7 26238.0 26326.6 26409.0 36954.4 37520.0 37650.1 37659.6 37672.6 227673.0 232722.0 236194.8 240113.0 241644.0 20865.1 21595.6 21674.6 22572.3 32941.2 37542.8 38099.6 38297.2 38298.0 38387.8 32909.3 33227.3 33385.8 33395.7 33662.5 32107.8 32285.7 32418.5 32559.5 34041.5 30611.2 31493.8 31706.4 31713.7 31717.1 27747.3 28666.7 29000.1 29300.0 29988.9 21799.8 22771.9 24196.3 24248.9 24780.8
09:46:20 INFO - PROCESS | 2228 |
09:46:20 INFO - PROCESS | 2228 | [#2] jquery.html Cycles:180 Average:20676.33 Median:5061.46 stddev:49908.33 (986.0%) stddev-sans-first:50024.84
09:46:20 INFO - PROCESS | 2228 | Values: 280.2 300.2 304.6 305.2 306.6 10675.9 11760.1 15513.8 15523.3 15789.8 14470.4 15052.2 15151.9 15586.5 15944.9 3980.7 4648.3 4648.4 4656.4 4667.2 3220.1 3370.3 4610.0 4670.1 4671.6 5013.9 5045.2 5225.5 5251.6 5328.7 5177.8 5320.8 5331.1 5349.2 5452.7 5054.3 5068.6 5138.7 5254.9 5273.5 10784.8 10847.2 12316.3 17878.4 19795.4 1604.8 1874.2 2196.0 2208.4 2263.1 7968.6 8426.0 8695.0 8696.7 10658.8 1592.4 1617.7 1670.7 1700.6 1716.7 62519.0 63812.7 64791.4 64814.7 65477.7 8587.4 8639.8 8783.3 8896.2 8911.4 10021.5 10593.6 10638.8 10701.7 11685.6 4784.3 4919.9 4944.6 5526.6 5897.1 5495.8 5508.1 5555.3 5638.3 5665.7 3626.9 3662.5 3685.4 3731.9 3760.7 1581.1 1612.3 1613.0 1617.8 1618.6 736.8 792.3 831.8 870.8 908.0 15895.0 15948.2 16026.9 16101.5 16587.8 734.5 743.8 784.5 786.4 789.3 8984.4 9024.0 9027.7 9243.4 9279.6 295372.0 295459.0 295788.5 296828.0 302490.0 77294.0 79674.6 80142.4 80232.0 81821.2 781.4 781.5 810.0 813.7 814.8 14583.5 14700.9 14949.5 15037.2 15086.2 35693.0 35962.6 36940.1 37337.8 37490.3 43195.0 43448.6 45778.4 48297.8 49894.8 6147.8 6420.7 6626.6 6678.6 6714.6 8806.0 11075.6 11166.4 11201.8 11313.0 8434.4 9251.0 9271.2 9499.0 9623.4 9153.1 9317.1 9451.3 9480.2 9581.3 9356.0 9498.0 9572.0 9656.4 9781.9 6580.7 6614.7 6747.6 6871.2 6979.3 8823.9 9418.2 9443.2 9641.2 9718.5
09:46:20 INFO - PROCESS | 2228 |
09:46:20 INFO - PROCESS | 2228 | [#3] mootools.html Cycles:180 Average:4099.73 Median:4142.04 stddev:2243.56 (54.2%) stddev-sans-first:2238.94
09:46:20 INFO - PROCESS | 2228 | Values: 1155.1 1191.3 1238.6 1434.6 1501.8 5870.5 6112.8 6598.3 6608.0 6642.1 5563.5 5624.1 6039.9 6160.2 6240.8 1769.9 1807.9 1870.7 1881.5 1881.6 1807.7 1838.8 1843.6 1849.6 1863.6 1608.3 1657.5 1775.6 1880.1 1880.6 1819.4 1826.5 1867.6 1889.1 1901.3 1741.6 1752.9 1774.8 1792.4 1806.4 5823.9 5956.0 6090.1 6210.0 6266.4 3855.5 3928.0 3936.3 3986.5 3987.2 2357.3 2434.4 2458.1 2476.8 2487.4 763.8 785.3 792.7 811.2 812.8 8155.8 8171.3 8206.7 8219.4 8221.6 3384.3 3508.9 3652.6 3657.2 3662.0 7131.7 7178.7 7195.5 7195.6 7199.4 3715.6 3800.8 3861.2 3863.9 3864.7 3505.4 3536.2 3539.4 3554.0 3557.5 2822.9 2998.3 3009.7 3024.3 3040.5 979.2 1006.5 1010.1 1013.4 1028.1 587.4 657.7 668.4 683.8 693.6 3851.2 3928.7 4068.5 4109.0 4154.9 341.0 341.1 341.5 345.2 347.8 2690.1 2771.2 2808.4 2814.3 2878.7 7294.2 7520.7 7585.9 7591.1 7679.4 7144.6 7209.6 7516.4 7526.1 7561.5 5587.8 5708.8 5710.6 5930.9 5963.7 4070.9 4072.6 4137.1 4147.0 4153.6 7155.4 7161.5 7165.8 7186.7 7198.7 6996.1 7019.9 7225.7 7330.5 7352.4 5230.8 5380.1 5399.0 5500.1 5561.7 5686.7 5848.5 5921.2 5943.2 5963.1 4813.8 4965.4 5563.6 5571.8 5597.3 5232.6 5337.9 5394.4 5543.8 5587.4 5123.6 5151.6 5237.0 5264.1 5266.8 3328.5 3384.3 3571.3 3618.9 3658.7 3591.1 3629.9 3666.6 3678.6 3687.5
09:46:20 INFO - PROCESS | 2228 |
09:46:20 INFO - PROCESS | 2228 | [#4] prototype.html Cycles:180 Average:3757.43 Median:4321.22 stddev:1111.48 (25.7%) stddev-sans-first:1082.83
09:46:20 INFO - PROCESS | 2228 | Values: 242.2 245.9 246.7 249.2 251.2 4145.6 4378.5 4381.7 4424.8 4425.8 4236.7 4296.7 4318.6 4358.7 4366.8 3157.3 3221.8 3279.7 3284.9 3286.0 3266.4 3286.5 3292.6 3294.3 3298.2 3075.6 3158.2 3169.5 3225.7 3227.2 3213.4 3247.7 3256.5 3262.5 3263.7 2835.7 2837.1 2872.1 2875.7 2876.8 4257.8 4272.0 4299.7 4303.5 4304.6 3653.5 3716.3 3808.4 3839.4 3839.4 4356.2 4370.1 4461.2 4519.4 4523.5 3228.7 3229.7 3232.1 3237.2 3241.6 4604.5 4624.5 4692.6 4703.2 4717.2 3299.9 3300.7 3301.8 3311.9 3314.1 3324.1 3339.3 3343.2 3343.3 3343.3 3519.7 3682.5 3800.3 3829.7 3829.7 4387.5 4453.9 4529.6 4533.9 4546.0 1035.5 1146.3 1155.3 1157.6 1160.8 717.4 775.8 802.6 805.0 807.9 4283.2 4314.4 4316.4 4323.8 4325.6 4542.5 4587.1 4625.1 4677.3 4678.2 4274.0 4312.3 4330.4 4332.2 4339.3 2402.7 2413.5 2427.5 2470.0 2490.0 4719.2 4721.6 4741.4 4742.2 4761.9 4504.8 4630.9 4731.2 4734.2 4747.5 4668.1 4673.4 4716.9 4720.3 4777.7 4455.8 4605.9 4617.3 4637.8 4665.7 4547.5 4549.5 4552.7 4557.0 4560.3 4625.0 4631.4 4714.2 4739.2 4740.5 3290.1 3306.4 3314.3 3316.8 3320.2 4432.6 4493.2 4546.6 4568.6 4607.1 4308.6 4325.5 4371.8 4383.9 4433.4 4250.3 4269.5 4403.4 4429.7 4454.0 4316.6 4387.3 4390.2 4390.6 4392.7 4190.8 4397.6 4504.9 4505.0 4507.2 4195.3 4277.7 4371.6 4415.1 4443.3
09:46:20 INFO - PROCESS | 2228 |
09:46:20 INFO - PROCESS | 2228 | [#5] yui.html Cycles:180 Average:16617.57 Median:541.92 stddev:24242.69 (4473.5%) stddev-sans-first:24280.70
09:46:20 INFO - PROCESS | 2228 | Values: 556.2 576.2 580.7 600.6 623.6 12814.8 13213.0 13281.7 13417.1 13666.6 12730.5 12842.5 12843.1 12871.5 13202.5 643.9 666.4 667.8 668.3 668.4 659.0 659.6 659.8 659.9 660.0 687.7 693.3 693.9 695.5 695.8 659.7 680.1 688.8 690.6 692.7 499.8 501.4 502.0 503.2 503.6 12973.9 13322.1 13436.6 13561.9 13563.5 12035.5 12240.9 12365.6 12638.8 12753.3 10418.4 10460.1 10462.6 10581.6 10665.8 3055.2 3163.7 3170.8 3186.9 3278.7 49710.0 59419.4 63135.7 63492.0 64766.0 9187.3 9300.2 9391.3 9393.0 9563.6 17436.5 17460.3 17462.3 17489.1 17492.5 5928.9 6134.7 6158.3 6199.8 6327.1 3494.8 3568.1 3585.5 3599.2 3714.8 2289.3 2491.4 2496.3 2506.7 2507.4 396.0 407.6 409.9 414.2 415.5 476.3 511.5 517.5 522.9 528.5 7146.1 7174.5 7460.9 7512.4 7535.7 465.3 485.8 507.0 533.7 550.1 3352.5 3635.3 3652.5 3676.2 3828.8 85001.0 91873.5 96069.0 96281.0 96300.5 79958.0 81474.0 82466.6 83535.2 83935.6 47799.0 72397.0 73821.3 74498.3 75043.0 6913.6 7138.4 7215.8 7318.5 7469.2 41335.6 44124.8 44252.3 44586.6 44722.6 39812.2 44220.6 61331.7 63919.0 64353.0 9204.2 9488.6 9714.2 9715.6 9945.9 10278.8 10434.6 10685.5 10900.7 11004.9 9625.2 10103.3 10113.3 10449.4 10582.3 9457.8 9910.0 10012.5 10019.1 10281.6 9734.4 9772.7 9794.0 9889.3 10024.3 8557.6 8606.3 8703.9 8936.7 9242.4 8048.4 8346.4 8352.2 8360.3 8722.9
09:46:20 INFO - PROCESS | 2228 | -------- Summary: end --------
09:46:20 INFO - PROCESS | 2228 |
09:46:21 INFO - TEST-INFO | 2228: exit 0
09:46:21 INFO - TEST-OK | dromaeo_css | took 1128700ms
09:46:21 INFO - TEST-START | kraken
09:46:21 INFO - operating with platform_type : w7_
09:46:21 INFO - Initialising browser for kraken test...
09:46:21 INFO - TEST-INFO | started process 3716 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpi9kk9k\profile http://localhost:49889/getInfo.html)
09:46:27 INFO - PROCESS | 3716 | __metrics Screen width/height:1600/1200
09:46:27 INFO - PROCESS | 3716 | colorDepth:24
09:46:27 INFO - PROCESS | 3716 | Browser inner width/height: 1010/674
09:46:27 INFO - PROCESS | 3716 | __metrics
09:46:28 INFO - PROCESS | 3716 | 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"
09:46:28 INFO - PROCESS | 3716 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
09:46:28 INFO - PROCESS | 3716 | console.error:
09:46:28 INFO - PROCESS | 3716 | Message: Error: Connection closed before committing the transaction.
09:46:28 INFO - PROCESS | 3716 | Stack:
09:46:28 INFO - PROCESS | 3716 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:937:23
09:46:28 INFO - PROCESS | 3716 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7
09:46:28 INFO - PROCESS | 3716 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11
09:46:28 INFO - PROCESS | 3716 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7
09:46:28 INFO - PROCESS | 3716 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7
09:46:28 INFO - PROCESS | 3716 | TaskImpl_run@resource://gre/modules/Task.jsm:324:13
09:46:28 INFO - PROCESS | 3716 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9
09:46:28 INFO - PROCESS | 3716 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15
09:46:28 INFO - PROCESS | 3716 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5
09:46:28 INFO - PROCESS | 3716 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29
09:46:28 INFO - PROCESS | 3716 | Barrier/this.client.addBlocker/promise13.0%
09:47:29 INFO - TinderboxPrint: I/O read bytes / time
75,217,920 / 1,391,320
09:47:29 INFO - TinderboxPrint: I/O write bytes / time
484,888,576 / 3,942,370
09:47:29 INFO - TinderboxPrint: CPU idle
8,376.2 (87.0%)
09:47:29 INFO - TinderboxPrint: CPU user
1,222.0 (12.7%)
09:47:29 INFO - install - Wall time: 5s; CPU: 6.0%; Read bytes: 32768; Write bytes: 63378944; Read time: 1210; Write time: 277160
09:47:29 INFO - run-tests - Wall time: 1200s; CPU: 13.0%; Read bytes: 75185152; Write bytes: 403646976; Read time: 1390110; Write time: 3602010
09:47:29 INFO - Running post-run listener: _upload_blobber_files
09:47:29 INFO - Blob upload gear active.
09:47:29 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir.
09:47:29 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
09:47:29 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-Non-PGO', '-d', 'C:\\slave\\test\\build\\blobber_upload_dir', '--output-manifest', 'C:\\slave\\test\\build\\uploaded_files.json']
09:47:29 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-Non-PGO -d C:\slave\test\build\blobber_upload_dir --output-manifest C:\slave\test\build\uploaded_files.json
09:47:30 INFO - (blobuploader) - INFO - Open directory for files ...
09:47:30 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log ...
09:47:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
09:47:30 INFO - (blobuploader) - INFO - Uploading, attempt #1.
09:47:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded
09:47:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
09:47:31 INFO - (blobuploader) - INFO - Done attempting.
09:47:31 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log ...
09:47:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
09:47:31 INFO - (blobuploader) - INFO - Uploading, attempt #1.
09:47:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded
09:47:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
09:47:32 INFO - (blobuploader) - INFO - Done attempting.
09:47:32 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ...
09:47:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
09:47:32 INFO - (blobuploader) - INFO - Uploading, attempt #1.
09:47:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
09:47:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
09:47:40 INFO - (blobuploader) - INFO - Done attempting.
09:47:40 INFO - (blobuploader) - INFO - Iteration through files over.
09:47:40 INFO - Return code: 0
09:47:40 INFO - rmtree: C:\slave\test\build\uploaded_files.json
09:47:40 INFO - Using _rmtree_windows ...
09:47:40 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1
09:47:40 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/0b13b780c829a81d20cfdef3a6c50eb4bd5d887b9742bc1b928b05be06a5d32949af520403ce66fc3bb1b89088b3877e476a2a0d58398d50a4d3934661509340", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/747c5d21d48f2f81a62cf97d712349e15cdc452081938f7f766fc2dd7da6e655f00b8922ef1334156b9aef69a5eb1afca3fdbb1f01e52f3d7010d7b6767890c3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/3ab04f6811bd04e7d9d3b29a5c2128e7d373d0a2b86dbb142d6452f2553683ba77b1f273e17587a9e8c2b66fd4200a74176b6d6b42d65305b3c4d6dbcfc6251b"}
09:47:40 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files
09:47:40 INFO - Writing to file C:\slave\test\properties\blobber_files
09:47:40 INFO - Contents:
09:47:40 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/0b13b780c829a81d20cfdef3a6c50eb4bd5d887b9742bc1b928b05be06a5d32949af520403ce66fc3bb1b89088b3877e476a2a0d58398d50a4d3934661509340", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/747c5d21d48f2f81a62cf97d712349e15cdc452081938f7f766fc2dd7da6e655f00b8922ef1334156b9aef69a5eb1afca3fdbb1f01e52f3d7010d7b6767890c3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/3ab04f6811bd04e7d9d3b29a5c2128e7d373d0a2b86dbb142d6452f2553683ba77b1f273e17587a9e8c2b66fd4200a74176b6d6b42d65305b3c4d6dbcfc6251b"}
09:47:40 INFO - Running post-run listener: copy_logs_to_upload_dir
09:47:40 INFO - Copying logs to upload dir...
09:47:40 INFO - mkdir: C:\slave\test\build\upload\logs
09:47:40 INFO - Copying logs to upload dir...
09:47:40 INFO - Using _rmtree_windows ...
09:47:40 INFO - Using _rmtree_windows ...
09:47:40 INFO - Using _rmtree_windows ...
09:47:40 INFO - Using _rmtree_windows ...
09:47:40 INFO - Using _rmtree_windows ...
09:47:40 INFO - Using _rmtree_windows ...
09:47:40 INFO - Using _rmtree_windows ...
program finished with exit code 0
elapsedTime=1546.992000
========= master_lag: 0.08 =========
========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 25 mins, 47 secs) (at 2016-09-27 09:47:40.161748) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-27 09:47:40.162523) =========
'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-035
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-035
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-035
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-Non-PGO/sha512/0b13b780c829a81d20cfdef3a6c50eb4bd5d887b9742bc1b928b05be06a5d32949af520403ce66fc3bb1b89088b3877e476a2a0d58398d50a4d3934661509340", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/747c5d21d48f2f81a62cf97d712349e15cdc452081938f7f766fc2dd7da6e655f00b8922ef1334156b9aef69a5eb1afca3fdbb1f01e52f3d7010d7b6767890c3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/3ab04f6811bd04e7d9d3b29a5c2128e7d373d0a2b86dbb142d6452f2553683ba77b1f273e17587a9e8c2b66fd4200a74176b6d6b42d65305b3c4d6dbcfc6251b"}
build_url:https://queue.taskcluster.net/v1/task/Q1XRv_39T_i19CV2BUuI_A/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/Q1XRv_39T_i19CV2BUuI_A/artifacts/public/build/firefox-52.0a1.en-US.win32.zip'
blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/0b13b780c829a81d20cfdef3a6c50eb4bd5d887b9742bc1b928b05be06a5d32949af520403ce66fc3bb1b89088b3877e476a2a0d58398d50a4d3934661509340", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/747c5d21d48f2f81a62cf97d712349e15cdc452081938f7f766fc2dd7da6e655f00b8922ef1334156b9aef69a5eb1afca3fdbb1f01e52f3d7010d7b6767890c3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/3ab04f6811bd04e7d9d3b29a5c2128e7d373d0a2b86dbb142d6452f2553683ba77b1f273e17587a9e8c2b66fd4200a74176b6d6b42d65305b3c4d6dbcfc6251b"}'
========= master_lag: 0.01 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-27 09:47:40.276840) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-27 09:47:40.277186) =========
'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-035
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-035
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-035
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.05 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-27 09:47:40.427694) =========
========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-09-27 09:47:40.428017) =========
========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-09-27 09:47:40.936362) =========
========= Total master_lag: 0.32 =========