builder: fx-team_win7_ix_test-dromaeojs-pgo
slave: t-w732-ix-055
starttime: 1474980940.13
results: success (0)
buildid: 20160927033006
builduid: f6749104b0f24b6b9524a8c280e1e7ca
revision: cb212903f7036117ddbb29b3bdbc390b161b1bb2
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-27 05:55:40.126083) =========
master: http://buildbot-master119.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-27 05:55:40.126621) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 3 secs) (at 2016-09-27 05:55:40.126903) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 3 secs) (at 2016-09-27 05:55:43.843460) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-27 05:55:43.843798) =========
'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-055
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-055
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-055
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
--05:55:44-- 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.47 MB/s
05:55:45 (8.47 MB/s) - `archiver_client.py' saved [12141/12141]
program finished with exit code 0
elapsedTime=1.360000
========= master_lag: 0.05 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-27 05:55:45.251838) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 14 secs) (at 2016-09-27 05:55:45.252180) =========
'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-055
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-055
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-055
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
program finished with exit code 0
elapsedTime=14.001000
========= master_lag: 0.01 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 14 secs) (at 2016-09-27 05:55:59.261568) =========
========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-09-27 05:55:59.261935) =========
'bash' '-c' u'python archiver_client.py mozharness --repo integration/fx-team --rev cb212903f7036117ddbb29b3bdbc390b161b1bb2 --destination scripts --debug'
in dir C:\slave\test\. (timeout 1200 secs)
watching logfiles {}
argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev cb212903f7036117ddbb29b3bdbc390b161b1bb2 --destination scripts --debug']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-055
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-055
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-055
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 05:55:59,385 truncating revision to first 12 chars
2016-09-27 05:55:59,385 Setting DEBUG logging.
2016-09-27 05:55:59,385 attempt 1/10
2016-09-27 05:55:59,385 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/cb212903f703?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2016-09-27 05:56:01,049 unpacking tar archive at: fx-team-cb212903f703/testing/mozharness/
2016-09-27 05:56:01,247 skipping symlink on windows: configs/single_locale/linux32.py
program finished with exit code 0
elapsedTime=2.342000
========= master_lag: 0.16 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-09-27 05:56:01.765064) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-27 05:56:01.765371) =========
script_repo_revision: cb212903f7036117ddbb29b3bdbc390b161b1bb2
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-27 05:56:01.765773) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-27 05:56:01.766037) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-27 05:56:01.803870) =========
========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 54 secs) (at 2016-09-27 05:56:01.804151) =========
'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Fx-Team' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Fx-Team'
in dir C:\slave\test\. (timeout 3600 secs) (maxTime 7200 secs)
watching logfiles {}
argv: ['c:/mozilla-build/python27/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-055
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-055
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-055
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
05:56:02 INFO - MultiFileLogger online at 20160927 05:56:02 in C:\slave\test
05:56:02 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team
05:56:02 INFO - Dumping config to C:\slave\test\logs\localconfig.json.
05:56:02 INFO - {'append_to_log': False,
05:56:02 INFO - 'base_work_dir': 'C:\\slave\\test',
05:56:02 INFO - 'blob_upload_branch': 'Fx-Team',
05:56:02 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt',
05:56:02 INFO - 'branch': 'Fx-Team',
05:56:02 INFO - 'buildbot_json_path': 'buildprops.json',
05:56:02 INFO - 'config_files': ('talos/windows_config.py',),
05:56:02 INFO - 'default_actions': ('clobber',
05:56:02 INFO - 'read-buildbot-config',
05:56:02 INFO - 'download-and-extract',
05:56:02 INFO - 'populate-webroot',
05:56:02 INFO - 'create-virtualenv',
05:56:02 INFO - 'install',
05:56:02 INFO - 'run-tests'),
05:56:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
05:56:02 INFO - 'download_minidump_stackwalk': True,
05:56:02 INFO - 'download_symbols': 'ondemand',
05:56:02 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python',
05:56:02 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'),
05:56:02 INFO - 'hg': 'c:/mozilla-build/hg/hg',
05:56:02 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python',
05:56:02 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'),
05:56:02 INFO - 'python': 'c:/mozilla-build/python27/python.exe',
05:56:02 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe',
05:56:02 INFO - 'C:/mozilla-build/tooltool.py'),
05:56:02 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe',
05:56:02 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')},
05:56:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
05:56:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
05:56:02 INFO - 'installer_path': 'installer.exe',
05:56:02 INFO - 'log_level': 'info',
05:56:02 INFO - 'log_name': 'talos',
05:56:02 INFO - 'log_to_console': True,
05:56:02 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe',
05:56:02 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe',
05:56:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest',
05:56:02 INFO - 'opt_config_files': (),
05:56:02 INFO - 'pip_index': False,
05:56:02 INFO - 'sps_profile': False,
05:56:02 INFO - 'sps_profile_interval': 0,
05:56:02 INFO - 'suite': 'dromaeojs',
05:56:02 INFO - 'system_bits': '32',
05:56:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
05:56:02 INFO - 'title': 'T-W732-IX-055',
05:56:02 INFO - 'use_talos_json': True,
05:56:02 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'),
05:56:02 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv',
05:56:02 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll',
05:56:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
05:56:02 INFO - 'work_dir': 'build'}
05:56:02 INFO - [mozharness: 2016-09-27 12:56:02.543000Z] Running clobber step.
05:56:02 INFO - Running pre-action listener: _resource_record_pre_action
05:56:02 INFO - Running main action method: clobber
05:56:02 INFO - rmtree: C:\slave\test\build
05:56:02 INFO - Using _rmtree_windows ...
05:56:02 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1
05:57:33 INFO - Running post-action listener: _resource_record_post_action
05:57:33 INFO - [mozharness: 2016-09-27 12:57:33.857000Z] Finished clobber step (success)
05:57:33 INFO - [mozharness: 2016-09-27 12:57:33.858000Z] Running read-buildbot-config step.
05:57:33 INFO - Running pre-action listener: _resource_record_pre_action
05:57:33 INFO - Running main action method: read_buildbot_config
05:57:33 INFO - Using buildbot properties:
05:57:33 INFO - {
05:57:33 INFO - "project": "",
05:57:33 INFO - "product": "firefox",
05:57:33 INFO - "script_repo_revision": "production",
05:57:33 INFO - "builddir": "fx-team_win7_ix_test-dromaeojs",
05:57:33 INFO - "repository": "",
05:57:33 INFO - "buildername": "Windows 7 32-bit fx-team pgo talos dromaeojs",
05:57:33 INFO - "buildid": "20160927033006",
05:57:33 INFO - "pgo_build": "True",
05:57:33 INFO - "basedir": "C:\\slave\\test",
05:57:33 INFO - "buildnumber": 26,
05:57:33 INFO - "slavename": "t-w732-ix-055",
05:57:33 INFO - "master": "http://buildbot-master119.bb.releng.scl3.mozilla.com:8201/",
05:57:33 INFO - "platform": "win7_ix",
05:57:33 INFO - "scheduler": "tests-fx-team-win32-pgo-talos",
05:57:33 INFO - "branch": "fx-team",
05:57:33 INFO - "repo_path": "integration/fx-team",
05:57:33 INFO - "revision": "cb212903f7036117ddbb29b3bdbc390b161b1bb2",
05:57:33 INFO - "stage_platform": "win32-pgo",
05:57:33 INFO - "builduid": "f6749104b0f24b6b9524a8c280e1e7ca",
05:57:33 INFO - "slavebuilddir": "test"
05:57:33 INFO - }
05:57:33 INFO - Found installer url https://queue.taskcluster.net/v1/task/YPQvFkwPSKusQtWjjH_XKQ/artifacts/public/build/firefox-52.0a1.en-US.win32.zip.
05:57:33 INFO - Running post-action listener: _resource_record_post_action
05:57:33 INFO - [mozharness: 2016-09-27 12:57:33.861000Z] Finished read-buildbot-config step (success)
05:57:33 INFO - [mozharness: 2016-09-27 12:57:33.861000Z] Running download-and-extract step.
05:57:33 INFO - Running pre-action listener: _resource_record_pre_action
05:57:33 INFO - Running main action method: download_and_extract
05:57:33 INFO - mkdir: C:\slave\test\build\tests
05:57:33 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')]}
05:57:33 INFO - trying https://queue.taskcluster.net/v1/task/YPQvFkwPSKusQtWjjH_XKQ/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json
05:57:33 INFO - Downloading https://queue.taskcluster.net/v1/task/YPQvFkwPSKusQtWjjH_XKQ/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
05:57:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YPQvFkwPSKusQtWjjH_XKQ/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
05:57:34 INFO - Downloaded 1218 bytes.
05:57:34 INFO - Reading from file C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json
05:57:34 INFO - Using the following test package requirements:
05:57:34 INFO - {u'common': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
05:57:34 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
05:57:34 INFO - u'firefox-52.0a1.en-US.win32.cppunittest.tests.zip'],
05:57:34 INFO - u'gtest': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
05:57:34 INFO - u'jittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
05:57:34 INFO - u'jsshell-win32.zip'],
05:57:34 INFO - u'mochitest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
05:57:34 INFO - u'firefox-52.0a1.en-US.win32.mochitest.tests.zip'],
05:57:34 INFO - u'mozbase': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
05:57:34 INFO - u'reftest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
05:57:34 INFO - u'firefox-52.0a1.en-US.win32.reftest.tests.zip'],
05:57:34 INFO - u'talos': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
05:57:34 INFO - u'firefox-52.0a1.en-US.win32.talos.tests.zip'],
05:57:34 INFO - u'web-platform': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
05:57:34 INFO - u'firefox-52.0a1.en-US.win32.web-platform.tests.zip'],
05:57:34 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
05:57:34 INFO - u'firefox-52.0a1.en-US.win32.xpcshell.tests.zip']}
05:57:34 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip'] for test suite category: common
05:57:34 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/YPQvFkwPSKusQtWjjH_XKQ/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip
05:57:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YPQvFkwPSKusQtWjjH_XKQ/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1
05:57:34 INFO - Fetch https://queue.taskcluster.net/v1/task/YPQvFkwPSKusQtWjjH_XKQ/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory
05:57:35 INFO - Expected file size: 17322946
05:58:20 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
05:58:20 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/YPQvFkwPSKusQtWjjH_XKQ/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip
05:58:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YPQvFkwPSKusQtWjjH_XKQ/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1
05:58:20 INFO - Fetch https://queue.taskcluster.net/v1/task/YPQvFkwPSKusQtWjjH_XKQ/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory
05:58:20 INFO - Expected file size: 17322946
05:58:45 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/YPQvFkwPSKusQtWjjH_XKQ/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip
05:58:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YPQvFkwPSKusQtWjjH_XKQ/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip'}, attempt #1
05:58:45 INFO - Fetch https://queue.taskcluster.net/v1/task/YPQvFkwPSKusQtWjjH_XKQ/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip into memory
05:58:45 INFO - Expected file size: 14414057
05:58:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
05:58:52 INFO - trying https://queue.taskcluster.net/v1/task/YPQvFkwPSKusQtWjjH_XKQ/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
05:58:52 INFO - Downloading https://queue.taskcluster.net/v1/task/YPQvFkwPSKusQtWjjH_XKQ/artifacts/public/build/firefox-52.0a1.en-US.win32.zip to C:\slave\test\build\firefox-52.0a1.en-US.win32.zip
05:58:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YPQvFkwPSKusQtWjjH_XKQ/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
05:58:55 INFO - Downloaded 54767823 bytes.
05:58:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YPQvFkwPSKusQtWjjH_XKQ/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
05:58:55 INFO - mkdir: C:\slave\test\properties
05:58:55 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url
05:58:55 INFO - Writing to file C:\slave\test\properties\build_url
05:58:55 INFO - Contents:
05:58:55 INFO - build_url:https://queue.taskcluster.net/v1/task/YPQvFkwPSKusQtWjjH_XKQ/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
05:58:56 INFO - Running post-action listener: _resource_record_post_action
05:58:56 INFO - Running post-action listener: set_extra_try_arguments
05:58:56 INFO - [mozharness: 2016-09-27 12:58:56.554000Z] Finished download-and-extract step (success)
05:58:56 INFO - [mozharness: 2016-09-27 12:58:56.554000Z] Running populate-webroot step.
05:58:56 INFO - Running pre-action listener: _resource_record_pre_action
05:58:56 INFO - Running main action method: populate_webroot
05:58:56 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']},
05:58:56 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']},
05:58:56 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome',
05:58:56 INFO - u'--tppagecycles',
05:58:56 INFO - u'7'],
05:58:56 INFO - u'tests': [u'tsvgm']}},
05:58:56 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'],
05:58:56 INFO - u'tests': [u'tresize', u'tcanvasmark']},
05:58:56 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']},
05:58:56 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'],
05:58:56 INFO - u'tests': [u'dromaeo_css', u'kraken']},
05:58:56 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']},
05:58:56 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
05:58:56 INFO - u'talos_options': [u'--disable-e10s'],
05:58:56 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
05:58:56 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
05:58:56 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
05:58:56 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
05:58:56 INFO - u'talos_options': [u'--disable-e10s'],
05:58:56 INFO - u'tests': [u'damp', u'tps']},
05:58:56 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
05:58:56 INFO - u'tests': [u'damp', u'tps']},
05:58:56 INFO - u'g3': {u'talos_options': [u'--disable-e10s'],
05:58:56 INFO - u'tests': [u'dromaeo_dom']},
05:58:56 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']},
05:58:56 INFO - u'g4': {u'talos_options': [u'--disable-e10s'],
05:58:56 INFO - u'tests': [u'basic_compositor_video']},
05:58:56 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']},
05:58:56 INFO - u'other': {u'talos_options': [u'--disable-e10s'],
05:58:56 INFO - u'tests': [u'a11yr',
05:58:56 INFO - u'ts_paint',
05:58:56 INFO - u'tpaint',
05:58:56 INFO - u'sessionrestore',
05:58:56 INFO - u'sessionrestore_no_auto_restore',
05:58:56 INFO - u'tabpaint']},
05:58:56 INFO - u'other-e10s': {u'tests': [u'a11yr',
05:58:56 INFO - u'ts_paint',
05:58:56 INFO - u'tpaint',
05:58:56 INFO - u'sessionrestore',
05:58:56 INFO - u'sessionrestore_no_auto_restore',
05:58:56 INFO - u'tabpaint']},
05:58:56 INFO - u'other-e10s_l64': {u'tests': [u'a11yr',
05:58:56 INFO - u'ts_paint',
05:58:56 INFO - u'tpaint',
05:58:56 INFO - u'sessionrestore',
05:58:56 INFO - u'sessionrestore_no_auto_restore',
05:58:56 INFO - u'tabpaint']},
05:58:56 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr',
05:58:56 INFO - u'ts_paint',
05:58:56 INFO - u'tpaint',
05:58:56 INFO - u'sessionrestore',
05:58:56 INFO - u'sessionrestore_no_auto_restore',
05:58:56 INFO - u'tabpaint']},
05:58:56 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'],
05:58:56 INFO - u'tests': [u'a11yr',
05:58:56 INFO - u'ts_paint',
05:58:56 INFO - u'tpaint',
05:58:56 INFO - u'sessionrestore',
05:58:56 INFO - u'sessionrestore_no_auto_restore',
05:58:56 INFO - u'tabpaint']},
05:58:56 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'],
05:58:56 INFO - u'tests': [u'a11yr',
05:58:56 INFO - u'ts_paint',
05:58:56 INFO - u'tpaint',
05:58:56 INFO - u'sessionrestore',
05:58:56 INFO - u'sessionrestore_no_auto_restore',
05:58:56 INFO - u'tabpaint']},
05:58:56 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'],
05:58:56 INFO - u'tests': [u'tsvgx',
05:58:56 INFO - u'tsvgr_opacity',
05:58:56 INFO - u'tart',
05:58:56 INFO - u'tscrollx',
05:58:56 INFO - u'cart']},
05:58:56 INFO - u'svgr-e10s': {u'tests': [u'tsvgx',
05:58:56 INFO - u'tsvgr_opacity',
05:58:56 INFO - u'tart',
05:58:56 INFO - u'tscrollx',
05:58:56 INFO - u'cart']},
05:58:56 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
05:58:56 INFO - u'talos_options': [u'--disable-e10s'],
05:58:56 INFO - u'tests': [u'tp5o']},
05:58:56 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
05:58:56 INFO - u'tests': [u'tp5o']},
05:58:56 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
05:58:56 INFO - u'talos_options': [u'--disable-e10s',
05:58:56 INFO - u'--xperf_path',
05:58:56 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
05:58:56 INFO - u'tests': [u'tp5n']},
05:58:56 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
05:58:56 INFO - u'talos_options': [u'--xperf_path',
05:58:56 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
05:58:56 INFO - u'tests': [u'tp5n']}},
05:58:56 INFO - u'talos.zip': {u'path': u'',
05:58:56 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}}
05:58:56 INFO - Running post-action listener: _resource_record_post_action
05:58:56 INFO - [mozharness: 2016-09-27 12:58:56.614000Z] Finished populate-webroot step (success)
05:58:56 INFO - [mozharness: 2016-09-27 12:58:56.615000Z] Running create-virtualenv step.
05:58:56 INFO - Running pre-action listener: _resource_record_pre_action
05:58:56 INFO - Running main action method: create_virtualenv
05:58:56 INFO - Creating virtualenv C:\slave\test\build/venv
05:58:56 INFO - mkdir: C:\slave\test\build\venv\Scripts
05:58:56 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll
05:58:56 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'!
05:58:56 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
05:58:56 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv
05:59:09 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe
05:59:13 INFO - Installing distribute......................................................................................................................................................................................done.
05:59:15 INFO - Return code: 0
05:59:15 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version']
05:59:15 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version
05:59:15 INFO - Reading from file tmpfile_stdout
05:59:15 INFO - Output received:
05:59:15 INFO - pip 0.8.2 from c:\slave\test\build\venv\lib\site-packages\pip-0.8.2-py2.7.egg (python 2.7)
05:59:15 INFO - Using _rmtree_windows ...
05:59:15 INFO - Using _rmtree_windows ...
05:59:15 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv
05:59:15 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')]}
05:59:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
05:59:15 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')]}
05:59:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
05:59:15 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 0x018E8640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01922E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01862B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0193CE90>, '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 0x012AA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019A8520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-055', 'USERDOMAIN': 'T-W732-IX-055', '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-055', '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
05:59:15 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
05:59:15 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
05:59:15 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
05:59:15 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
05:59:15 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
05:59:15 INFO - 'COMPUTERNAME': 'T-W732-IX-055',
05:59:15 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
05:59:15 INFO - 'CYGWINBASE': 'C:\\cygwin',
05:59:15 INFO - 'DCLOCATION': 'SCL3',
05:59:15 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
05:59:15 INFO - 'FP_NO_HOST_CHECK': 'NO',
05:59:15 INFO - 'HOMEDRIVE': 'C:',
05:59:15 INFO - 'HOMEPATH': '\\Users\\cltbld',
05:59:15 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
05:59:15 INFO - 'KTS_VERSION': '1.19c',
05:59:15 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
05:59:15 INFO - 'LOGONSERVER': '\\\\T-W732-IX-055',
05:59:15 INFO - 'MONDIR': 'C:\\Monitor_config\\',
05:59:15 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
05:59:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
05:59:15 INFO - 'MOZ_NO_REMOTE': '1',
05:59:15 INFO - 'NO_EM_RESTART': '1',
05:59:15 INFO - 'NUMBER_OF_PROCESSORS': '8',
05:59:15 INFO - 'OS': 'Windows_NT',
05:59:15 INFO - 'OURDRIVE': 'C:',
05:59:15 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;',
05:59:15 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
05:59:15 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
05:59:15 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
05:59:15 INFO - 'PROCESSOR_LEVEL': '6',
05:59:15 INFO - 'PROCESSOR_REVISION': '1e05',
05:59:15 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
05:59:15 INFO - 'PROGRAMFILES': 'C:\\Program Files',
05:59:15 INFO - 'PROMPT': '$P$G',
05:59:15 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
05:59:15 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
05:59:15 INFO - 'PUBLIC': 'C:\\Users\\Public',
05:59:15 INFO - 'PWD': 'C:\\slave\\test',
05:59:15 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
05:59:15 INFO - 'SLAVEDIR': 'C:\\slave\\',
05:59:15 INFO - 'SYSTEMDRIVE': 'C:',
05:59:15 INFO - 'SYSTEMROOT': 'C:\\windows',
05:59:15 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
05:59:15 INFO - 'TEST1': 'testie',
05:59:15 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
05:59:15 INFO - 'USERDOMAIN': 'T-W732-IX-055',
05:59:15 INFO - 'USERNAME': 'cltbld',
05:59:15 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
05:59:15 INFO - 'WINDIR': 'C:\\windows',
05:59:15 INFO - 'WINDOWS_TRACING_FLAGS': '3',
05:59:15 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
05:59:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
05:59:16 INFO - Ignoring indexes: http://pypi.python.org/simple/
05:59:16 INFO - Downloading/unpacking pip>=1.5
05:59:21 INFO - Running setup.py egg_info for package pip
05:59:21 INFO - warning: no files found matching 'pip\cacert.pem'
05:59:21 INFO - warning: no files found matching '*.html' under directory 'docs'
05:59:21 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
05:59:21 INFO - no previously-included directories found matching 'docs\_build\_sources'
05:59:21 INFO - Installing collected packages: pip
05:59:21 INFO - Found existing installation: pip 0.8.2
05:59:21 INFO - Uninstalling pip:
05:59:21 INFO - Successfully uninstalled pip
05:59:21 INFO - Running setup.py install for pip
05:59:21 INFO - warning: no files found matching 'pip\cacert.pem'
05:59:21 INFO - warning: no files found matching '*.html' under directory 'docs'
05:59:21 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
05:59:21 INFO - no previously-included directories found matching 'docs\_build\_sources'
05:59:21 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts
05:59:21 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts
05:59:21 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts
05:59:21 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts
05:59:21 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts
05:59:21 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts
05:59:21 INFO - Successfully installed pip
05:59:21 INFO - Cleaning up...
05:59:21 INFO - Return code: 0
05:59:21 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv
05:59:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
05:59:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
05:59:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
05:59:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
05:59:21 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018E8640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01922E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01862B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0193CE90>, '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 0x012AA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019A8520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-055', 'USERDOMAIN': 'T-W732-IX-055', '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-055', '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
05:59:21 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
05:59:21 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
05:59:21 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
05:59:21 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
05:59:21 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
05:59:21 INFO - 'COMPUTERNAME': 'T-W732-IX-055',
05:59:21 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
05:59:21 INFO - 'CYGWINBASE': 'C:\\cygwin',
05:59:21 INFO - 'DCLOCATION': 'SCL3',
05:59:21 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
05:59:21 INFO - 'FP_NO_HOST_CHECK': 'NO',
05:59:21 INFO - 'HOMEDRIVE': 'C:',
05:59:21 INFO - 'HOMEPATH': '\\Users\\cltbld',
05:59:21 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
05:59:21 INFO - 'KTS_VERSION': '1.19c',
05:59:21 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
05:59:21 INFO - 'LOGONSERVER': '\\\\T-W732-IX-055',
05:59:21 INFO - 'MONDIR': 'C:\\Monitor_config\\',
05:59:21 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
05:59:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
05:59:21 INFO - 'MOZ_NO_REMOTE': '1',
05:59:21 INFO - 'NO_EM_RESTART': '1',
05:59:21 INFO - 'NUMBER_OF_PROCESSORS': '8',
05:59:21 INFO - 'OS': 'Windows_NT',
05:59:21 INFO - 'OURDRIVE': 'C:',
05:59:21 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;',
05:59:21 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
05:59:21 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
05:59:21 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
05:59:21 INFO - 'PROCESSOR_LEVEL': '6',
05:59:21 INFO - 'PROCESSOR_REVISION': '1e05',
05:59:21 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
05:59:21 INFO - 'PROGRAMFILES': 'C:\\Program Files',
05:59:21 INFO - 'PROMPT': '$P$G',
05:59:21 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
05:59:21 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
05:59:21 INFO - 'PUBLIC': 'C:\\Users\\Public',
05:59:21 INFO - 'PWD': 'C:\\slave\\test',
05:59:21 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
05:59:21 INFO - 'SLAVEDIR': 'C:\\slave\\',
05:59:21 INFO - 'SYSTEMDRIVE': 'C:',
05:59:21 INFO - 'SYSTEMROOT': 'C:\\windows',
05:59:21 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
05:59:21 INFO - 'TEST1': 'testie',
05:59:21 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
05:59:21 INFO - 'USERDOMAIN': 'T-W732-IX-055',
05:59:21 INFO - 'USERNAME': 'cltbld',
05:59:21 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
05:59:21 INFO - 'WINDIR': 'C:\\windows',
05:59:21 INFO - 'WINDOWS_TRACING_FLAGS': '3',
05:59:21 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
05:59:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
05:59:23 INFO - Ignoring indexes: https://pypi.python.org/simple/
05:59:23 INFO - Downloading/unpacking psutil>=3.1.1
05:59:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
05:59:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
05:59:24 INFO - Installing collected packages: psutil
05:59:24 INFO - Successfully installed psutil
05:59:24 INFO - Cleaning up...
05:59:24 INFO - Return code: 0
05:59:24 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv
05:59:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
05:59:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
05:59:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
05:59:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
05:59:24 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 0x018E8640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01922E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01862B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0193CE90>, '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 0x012AA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019A8520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-055', 'USERDOMAIN': 'T-W732-IX-055', '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-055', '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
05:59:24 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
05:59:24 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
05:59:24 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
05:59:24 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
05:59:24 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
05:59:24 INFO - 'COMPUTERNAME': 'T-W732-IX-055',
05:59:24 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
05:59:24 INFO - 'CYGWINBASE': 'C:\\cygwin',
05:59:24 INFO - 'DCLOCATION': 'SCL3',
05:59:24 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
05:59:24 INFO - 'FP_NO_HOST_CHECK': 'NO',
05:59:24 INFO - 'HOMEDRIVE': 'C:',
05:59:24 INFO - 'HOMEPATH': '\\Users\\cltbld',
05:59:24 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
05:59:24 INFO - 'KTS_VERSION': '1.19c',
05:59:24 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
05:59:24 INFO - 'LOGONSERVER': '\\\\T-W732-IX-055',
05:59:24 INFO - 'MONDIR': 'C:\\Monitor_config\\',
05:59:24 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
05:59:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
05:59:24 INFO - 'MOZ_NO_REMOTE': '1',
05:59:24 INFO - 'NO_EM_RESTART': '1',
05:59:24 INFO - 'NUMBER_OF_PROCESSORS': '8',
05:59:24 INFO - 'OS': 'Windows_NT',
05:59:24 INFO - 'OURDRIVE': 'C:',
05:59:24 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
05:59:24 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
05:59:24 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
05:59:24 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
05:59:24 INFO - 'PROCESSOR_LEVEL': '6',
05:59:24 INFO - 'PROCESSOR_REVISION': '1e05',
05:59:24 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
05:59:24 INFO - 'PROGRAMFILES': 'C:\\Program Files',
05:59:24 INFO - 'PROMPT': '$P$G',
05:59:24 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
05:59:24 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
05:59:24 INFO - 'PUBLIC': 'C:\\Users\\Public',
05:59:24 INFO - 'PWD': 'C:\\slave\\test',
05:59:24 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
05:59:24 INFO - 'SLAVEDIR': 'C:\\slave\\',
05:59:24 INFO - 'SYSTEMDRIVE': 'C:',
05:59:24 INFO - 'SYSTEMROOT': 'C:\\windows',
05:59:24 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
05:59:24 INFO - 'TEST1': 'testie',
05:59:24 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
05:59:24 INFO - 'USERDOMAIN': 'T-W732-IX-055',
05:59:24 INFO - 'USERNAME': 'cltbld',
05:59:24 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
05:59:24 INFO - 'WINDIR': 'C:\\windows',
05:59:24 INFO - 'WINDOWS_TRACING_FLAGS': '3',
05:59:24 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
05:59:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
05:59:25 INFO - Ignoring indexes: https://pypi.python.org/simple/
05:59:25 INFO - Downloading/unpacking mozsystemmonitor==0.3
05:59:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
05:59:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
05:59:26 INFO - Downloading mozsystemmonitor-0.3.tar.gz
05:59:26 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor
05:59:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in c:\slave\test\build\venv\lib\site-packages (from mozsystemmonitor==0.3)
05:59:26 INFO - Installing collected packages: mozsystemmonitor
05:59:26 INFO - Running setup.py install for mozsystemmonitor
05:59:26 INFO - Successfully installed mozsystemmonitor
05:59:26 INFO - Cleaning up...
05:59:27 INFO - Return code: 0
05:59:27 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv
05:59:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
05:59:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
05:59:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
05:59:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
05:59:27 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018E8640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01922E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01862B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0193CE90>, '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 0x012AA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019A8520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-055', 'USERDOMAIN': 'T-W732-IX-055', '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-055', '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
05:59:27 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in C:\slave\test\build
05:59:27 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1
05:59:27 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
05:59:27 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
05:59:27 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
05:59:27 INFO - 'COMPUTERNAME': 'T-W732-IX-055',
05:59:27 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
05:59:27 INFO - 'CYGWINBASE': 'C:\\cygwin',
05:59:27 INFO - 'DCLOCATION': 'SCL3',
05:59:27 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
05:59:27 INFO - 'FP_NO_HOST_CHECK': 'NO',
05:59:27 INFO - 'HOMEDRIVE': 'C:',
05:59:27 INFO - 'HOMEPATH': '\\Users\\cltbld',
05:59:27 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
05:59:27 INFO - 'KTS_VERSION': '1.19c',
05:59:27 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
05:59:27 INFO - 'LOGONSERVER': '\\\\T-W732-IX-055',
05:59:27 INFO - 'MONDIR': 'C:\\Monitor_config\\',
05:59:27 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
05:59:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
05:59:27 INFO - 'MOZ_NO_REMOTE': '1',
05:59:27 INFO - 'NO_EM_RESTART': '1',
05:59:27 INFO - 'NUMBER_OF_PROCESSORS': '8',
05:59:27 INFO - 'OS': 'Windows_NT',
05:59:27 INFO - 'OURDRIVE': 'C:',
05:59:27 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
05:59:27 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
05:59:27 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
05:59:27 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
05:59:27 INFO - 'PROCESSOR_LEVEL': '6',
05:59:27 INFO - 'PROCESSOR_REVISION': '1e05',
05:59:27 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
05:59:27 INFO - 'PROGRAMFILES': 'C:\\Program Files',
05:59:27 INFO - 'PROMPT': '$P$G',
05:59:27 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
05:59:27 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
05:59:27 INFO - 'PUBLIC': 'C:\\Users\\Public',
05:59:27 INFO - 'PWD': 'C:\\slave\\test',
05:59:27 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
05:59:27 INFO - 'SLAVEDIR': 'C:\\slave\\',
05:59:27 INFO - 'SYSTEMDRIVE': 'C:',
05:59:27 INFO - 'SYSTEMROOT': 'C:\\windows',
05:59:27 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
05:59:27 INFO - 'TEST1': 'testie',
05:59:27 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
05:59:27 INFO - 'USERDOMAIN': 'T-W732-IX-055',
05:59:27 INFO - 'USERNAME': 'cltbld',
05:59:27 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
05:59:27 INFO - 'WINDIR': 'C:\\windows',
05:59:27 INFO - 'WINDOWS_TRACING_FLAGS': '3',
05:59:27 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
05:59:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
05:59:27 INFO - Ignoring indexes: https://pypi.python.org/simple/
05:59:27 INFO - Downloading/unpacking jsonschema==2.5.1
05:59:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
05:59:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
05:59:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl
05:59:28 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1)
05:59:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
05:59:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
05:59:28 INFO - Downloading functools32-3.2.3-2.tar.gz
05:59:28 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32
05:59:28 INFO - warning: no files found matching '*.txt'
05:59:28 INFO - no previously-included directories found matching 'build'
05:59:28 INFO - no previously-included directories found matching 'dist'
05:59:28 INFO - no previously-included directories found matching '.git*'
05:59:28 INFO - Installing collected packages: jsonschema, functools32
05:59:29 INFO - Running setup.py install for functools32
05:59:29 INFO - warning: no files found matching '*.txt'
05:59:29 INFO - no previously-included directories found matching 'build'
05:59:29 INFO - no previously-included directories found matching 'dist'
05:59:29 INFO - no previously-included directories found matching '.git*'
05:59:29 INFO - Successfully installed jsonschema functools32
05:59:29 INFO - Cleaning up...
05:59:29 INFO - Return code: 0
05:59:29 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv
05:59: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')]}
05:59:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
05:59: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')]}
05:59:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
05:59:29 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018E8640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01922E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01862B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0193CE90>, '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 0x012AA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019A8520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-055', 'USERDOMAIN': 'T-W732-IX-055', '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-055', '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
05:59:29 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in C:\slave\test\build
05:59:29 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2
05:59:29 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
05:59:29 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
05:59:29 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
05:59:29 INFO - 'COMPUTERNAME': 'T-W732-IX-055',
05:59:29 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
05:59:29 INFO - 'CYGWINBASE': 'C:\\cygwin',
05:59:29 INFO - 'DCLOCATION': 'SCL3',
05:59:29 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
05:59:29 INFO - 'FP_NO_HOST_CHECK': 'NO',
05:59:29 INFO - 'HOMEDRIVE': 'C:',
05:59:29 INFO - 'HOMEPATH': '\\Users\\cltbld',
05:59:29 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
05:59:29 INFO - 'KTS_VERSION': '1.19c',
05:59:29 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
05:59:29 INFO - 'LOGONSERVER': '\\\\T-W732-IX-055',
05:59:29 INFO - 'MONDIR': 'C:\\Monitor_config\\',
05:59:29 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
05:59:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
05:59:29 INFO - 'MOZ_NO_REMOTE': '1',
05:59:29 INFO - 'NO_EM_RESTART': '1',
05:59:29 INFO - 'NUMBER_OF_PROCESSORS': '8',
05:59:29 INFO - 'OS': 'Windows_NT',
05:59:29 INFO - 'OURDRIVE': 'C:',
05:59:29 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
05:59:29 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
05:59:29 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
05:59:29 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
05:59:29 INFO - 'PROCESSOR_LEVEL': '6',
05:59:29 INFO - 'PROCESSOR_REVISION': '1e05',
05:59:29 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
05:59:29 INFO - 'PROGRAMFILES': 'C:\\Program Files',
05:59:29 INFO - 'PROMPT': '$P$G',
05:59:29 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
05:59:29 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
05:59:29 INFO - 'PUBLIC': 'C:\\Users\\Public',
05:59:29 INFO - 'PWD': 'C:\\slave\\test',
05:59:29 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
05:59:29 INFO - 'SLAVEDIR': 'C:\\slave\\',
05:59:29 INFO - 'SYSTEMDRIVE': 'C:',
05:59:29 INFO - 'SYSTEMROOT': 'C:\\windows',
05:59:29 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
05:59:29 INFO - 'TEST1': 'testie',
05:59:29 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
05:59:29 INFO - 'USERDOMAIN': 'T-W732-IX-055',
05:59:29 INFO - 'USERNAME': 'cltbld',
05:59:29 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
05:59:29 INFO - 'WINDIR': 'C:\\windows',
05:59:29 INFO - 'WINDOWS_TRACING_FLAGS': '3',
05:59:29 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
05:59:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
05:59:30 INFO - Ignoring indexes: https://pypi.python.org/simple/
05:59:30 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages
05:59:30 INFO - Cleaning up...
05:59:30 INFO - Return code: 0
05:59:30 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv
05:59:30 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')]}
05:59:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
05:59:30 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')]}
05:59:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
05:59:30 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 0x018E8640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01922E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01862B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0193CE90>, '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 0x012AA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019A8520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-055', 'USERDOMAIN': 'T-W732-IX-055', '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-055', '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
05:59:30 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
05:59:30 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
05:59:30 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
05:59:30 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
05:59:30 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
05:59:30 INFO - 'COMPUTERNAME': 'T-W732-IX-055',
05:59:30 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
05:59:30 INFO - 'CYGWINBASE': 'C:\\cygwin',
05:59:30 INFO - 'DCLOCATION': 'SCL3',
05:59:30 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
05:59:30 INFO - 'FP_NO_HOST_CHECK': 'NO',
05:59:30 INFO - 'HOMEDRIVE': 'C:',
05:59:30 INFO - 'HOMEPATH': '\\Users\\cltbld',
05:59:30 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
05:59:30 INFO - 'KTS_VERSION': '1.19c',
05:59:30 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
05:59:30 INFO - 'LOGONSERVER': '\\\\T-W732-IX-055',
05:59:30 INFO - 'MONDIR': 'C:\\Monitor_config\\',
05:59:30 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
05:59:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
05:59:30 INFO - 'MOZ_NO_REMOTE': '1',
05:59:30 INFO - 'NO_EM_RESTART': '1',
05:59:30 INFO - 'NUMBER_OF_PROCESSORS': '8',
05:59:30 INFO - 'OS': 'Windows_NT',
05:59:30 INFO - 'OURDRIVE': 'C:',
05:59:30 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;',
05:59:30 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
05:59:30 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
05:59:30 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
05:59:30 INFO - 'PROCESSOR_LEVEL': '6',
05:59:30 INFO - 'PROCESSOR_REVISION': '1e05',
05:59:30 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
05:59:30 INFO - 'PROGRAMFILES': 'C:\\Program Files',
05:59:30 INFO - 'PROMPT': '$P$G',
05:59:30 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
05:59:30 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
05:59:30 INFO - 'PUBLIC': 'C:\\Users\\Public',
05:59:30 INFO - 'PWD': 'C:\\slave\\test',
05:59:30 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
05:59:30 INFO - 'SLAVEDIR': 'C:\\slave\\',
05:59:30 INFO - 'SYSTEMDRIVE': 'C:',
05:59:30 INFO - 'SYSTEMROOT': 'C:\\windows',
05:59:30 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
05:59:30 INFO - 'TEST1': 'testie',
05:59:30 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
05:59:30 INFO - 'USERDOMAIN': 'T-W732-IX-055',
05:59:30 INFO - 'USERNAME': 'cltbld',
05:59:30 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
05:59:30 INFO - 'WINDIR': 'C:\\windows',
05:59:30 INFO - 'WINDOWS_TRACING_FLAGS': '3',
05:59:30 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
05:59:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
05:59:30 INFO - Ignoring indexes: https://pypi.python.org/simple/
05:59:30 INFO - Downloading/unpacking blobuploader==1.2.4
05:59:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
05:59:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
05:59:31 INFO - Downloading blobuploader-1.2.4.tar.gz
05:59:31 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader
05:59:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4)
05:59:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
05:59:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
05:59:31 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests
05:59:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4)
05:59:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
05:59:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
05:59:33 INFO - Downloading docopt-0.6.1.tar.gz
05:59:33 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt
05:59:33 INFO - Installing collected packages: blobuploader, requests, docopt
05:59:33 INFO - Running setup.py install for blobuploader
05:59:33 INFO - Running setup.py install for requests
05:59:33 INFO - Running setup.py install for docopt
05:59:34 INFO - Successfully installed blobuploader requests docopt
05:59:34 INFO - Cleaning up...
05:59:35 INFO - Return code: 0
05:59:35 INFO - Installing None into virtualenv C:\slave\test\build/venv
05:59:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
05:59:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
05:59:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
05:59:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
05:59:35 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 0x018E8640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01922E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01862B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0193CE90>, '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 0x012AA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019A8520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-055', 'USERDOMAIN': 'T-W732-IX-055', '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-055', '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
05:59:35 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
05:59:35 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
05:59:35 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
05:59:35 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
05:59:35 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
05:59:35 INFO - 'COMPUTERNAME': 'T-W732-IX-055',
05:59:35 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
05:59:35 INFO - 'CYGWINBASE': 'C:\\cygwin',
05:59:35 INFO - 'DCLOCATION': 'SCL3',
05:59:35 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
05:59:35 INFO - 'FP_NO_HOST_CHECK': 'NO',
05:59:35 INFO - 'HOMEDRIVE': 'C:',
05:59:35 INFO - 'HOMEPATH': '\\Users\\cltbld',
05:59:35 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
05:59:35 INFO - 'KTS_VERSION': '1.19c',
05:59:35 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
05:59:35 INFO - 'LOGONSERVER': '\\\\T-W732-IX-055',
05:59:35 INFO - 'MONDIR': 'C:\\Monitor_config\\',
05:59:35 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
05:59:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
05:59:35 INFO - 'MOZ_NO_REMOTE': '1',
05:59:35 INFO - 'NO_EM_RESTART': '1',
05:59:35 INFO - 'NUMBER_OF_PROCESSORS': '8',
05:59:35 INFO - 'OS': 'Windows_NT',
05:59:35 INFO - 'OURDRIVE': 'C:',
05:59:35 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;',
05:59:35 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
05:59:35 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
05:59:35 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
05:59:35 INFO - 'PROCESSOR_LEVEL': '6',
05:59:35 INFO - 'PROCESSOR_REVISION': '1e05',
05:59:35 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
05:59:35 INFO - 'PROGRAMFILES': 'C:\\Program Files',
05:59:35 INFO - 'PROMPT': '$P$G',
05:59:35 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
05:59:35 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
05:59:35 INFO - 'PUBLIC': 'C:\\Users\\Public',
05:59:35 INFO - 'PWD': 'C:\\slave\\test',
05:59:35 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
05:59:35 INFO - 'SLAVEDIR': 'C:\\slave\\',
05:59:35 INFO - 'SYSTEMDRIVE': 'C:',
05:59:35 INFO - 'SYSTEMROOT': 'C:\\windows',
05:59:35 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
05:59:35 INFO - 'TEST1': 'testie',
05:59:35 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
05:59:35 INFO - 'USERDOMAIN': 'T-W732-IX-055',
05:59:35 INFO - 'USERNAME': 'cltbld',
05:59:35 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
05:59:35 INFO - 'WINDIR': 'C:\\windows',
05:59:35 INFO - 'WINDOWS_TRACING_FLAGS': '3',
05:59:35 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
05:59:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
05:59:36 INFO - Ignoring indexes: https://pypi.python.org/simple/
05:59:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
05:59:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gc0m_c-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
05:59:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
05:59:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-uqwbwk-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
05:59:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
05:59:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vupedg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
05:59:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
05:59:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gjhfw4-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
05:59:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
05:59:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-nzuilo-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
05:59:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
05:59:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-uz1luh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
05:59:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
05:59:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-v9ohlz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
05:59:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
05:59:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-eqek6x-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
05:59:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
05:59:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dgzjfc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
05:59:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
05:59:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xzo2jt-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
05:59:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
05:59:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-iesxba-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
05:59:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
05:59:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rapuur-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
05:59:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
05:59:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mwdvow-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
05:59:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
05:59:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gg2lvz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
05:59:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
05:59:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mp_wmy-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
05:59:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
05:59:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bolblg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
05:59:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
05:59:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-sepxxw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
05:59:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
05:59:38 INFO - Running setup.py install for manifestparser
05:59:38 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts
05:59:38 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts
05:59:39 INFO - Running setup.py install for mozcrash
05:59:39 INFO - Running setup.py install for mozdebug
05:59:39 INFO - Running setup.py install for mozdevice
05:59:39 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts
05:59:39 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts
05:59:39 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts
05:59:39 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts
05:59:40 INFO - Running setup.py install for mozfile
05:59:40 INFO - Running setup.py install for mozhttpd
05:59:40 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts
05:59:40 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts
05:59:41 INFO - Running setup.py install for mozinfo
05:59:41 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts
05:59:41 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts
05:59:41 INFO - Running setup.py install for mozInstall
05:59:41 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts
05:59:41 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts
05:59:41 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts
05:59:41 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts
05:59:41 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts
05:59:41 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts
05:59:41 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts
05:59:41 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts
05:59:41 INFO - Running setup.py install for mozleak
05:59:42 INFO - Running setup.py install for mozlog
05:59:42 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts
05:59:42 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts
05:59:43 INFO - Running setup.py install for moznetwork
05:59:43 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts
05:59:43 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts
05:59:43 INFO - Running setup.py install for mozprocess
05:59:43 INFO - Running setup.py install for mozprofile
05:59:44 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts
05:59:44 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts
05:59:44 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts
05:59:44 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts
05:59:44 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts
05:59:44 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts
05:59:44 INFO - Running setup.py install for mozrunner
05:59:44 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts
05:59:44 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts
05:59:45 INFO - Running setup.py install for mozscreenshot
05:59:45 INFO - Running setup.py install for moztest
05:59:46 INFO - Running setup.py install for mozversion
05:59:46 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts
05:59:46 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts
05:59:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion
05:59:46 INFO - Cleaning up...
05:59:46 INFO - Return code: 0
05:59:46 INFO - Installing None into virtualenv C:\slave\test\build/venv
05:59:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
05:59:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
05:59:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
05:59:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
05:59:46 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 0x018E8640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01922E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01862B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0193CE90>, '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 0x012AA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019A8520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-055', 'USERDOMAIN': 'T-W732-IX-055', '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-055', '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
05:59:46 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
05:59:46 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
05:59:46 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
05:59:46 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
05:59:46 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
05:59:46 INFO - 'COMPUTERNAME': 'T-W732-IX-055',
05:59:46 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
05:59:46 INFO - 'CYGWINBASE': 'C:\\cygwin',
05:59:46 INFO - 'DCLOCATION': 'SCL3',
05:59:46 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
05:59:46 INFO - 'FP_NO_HOST_CHECK': 'NO',
05:59:46 INFO - 'HOMEDRIVE': 'C:',
05:59:46 INFO - 'HOMEPATH': '\\Users\\cltbld',
05:59:46 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
05:59:46 INFO - 'KTS_VERSION': '1.19c',
05:59:46 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
05:59:46 INFO - 'LOGONSERVER': '\\\\T-W732-IX-055',
05:59:46 INFO - 'MONDIR': 'C:\\Monitor_config\\',
05:59:46 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
05:59:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
05:59:46 INFO - 'MOZ_NO_REMOTE': '1',
05:59:46 INFO - 'NO_EM_RESTART': '1',
05:59:46 INFO - 'NUMBER_OF_PROCESSORS': '8',
05:59:46 INFO - 'OS': 'Windows_NT',
05:59:46 INFO - 'OURDRIVE': 'C:',
05:59:46 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
05:59:46 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
05:59:46 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
05:59:46 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
05:59:46 INFO - 'PROCESSOR_LEVEL': '6',
05:59:46 INFO - 'PROCESSOR_REVISION': '1e05',
05:59:46 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
05:59:46 INFO - 'PROGRAMFILES': 'C:\\Program Files',
05:59:46 INFO - 'PROMPT': '$P$G',
05:59:46 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
05:59:46 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
05:59:46 INFO - 'PUBLIC': 'C:\\Users\\Public',
05:59:46 INFO - 'PWD': 'C:\\slave\\test',
05:59:46 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
05:59:46 INFO - 'SLAVEDIR': 'C:\\slave\\',
05:59:46 INFO - 'SYSTEMDRIVE': 'C:',
05:59:46 INFO - 'SYSTEMROOT': 'C:\\windows',
05:59:46 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
05:59:46 INFO - 'TEST1': 'testie',
05:59:46 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
05:59:46 INFO - 'USERDOMAIN': 'T-W732-IX-055',
05:59:46 INFO - 'USERNAME': 'cltbld',
05:59:46 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
05:59:46 INFO - 'WINDIR': 'C:\\windows',
05:59:46 INFO - 'WINDOWS_TRACING_FLAGS': '3',
05:59:46 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
05:59:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
05:59:46 INFO - Ignoring indexes: https://pypi.python.org/simple/
05:59:46 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
05:59:46 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-yqf35l-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
05:59:46 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///C:/slave/test/build/tests/mozbase/manifestparser in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 1))
05:59:46 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
05:59:46 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-9nra9h-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
05:59:47 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))
05:59:47 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
05:59:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-x22jk1-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
05:59:47 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))
05:59:47 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
05:59:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-2cyjp2-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
05:59:47 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))
05:59:47 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
05:59:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-plschg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
05:59:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///C:/slave/test/build/tests/mozbase/mozfile in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 5))
05:59:47 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
05:59:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fvbwc6-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
05:59:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///C:/slave/test/build/tests/mozbase/mozhttpd in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 6))
05:59:47 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
05:59:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xunyjw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
05:59:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///C:/slave/test/build/tests/mozbase/mozinfo in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 7))
05:59:47 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
05:59:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jpoqt1-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
05:59:48 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))
05:59:48 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
05:59:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-1pzy9e-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
05:59:48 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))
05:59:48 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
05:59:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-b67fvv-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
05:59:48 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))
05:59:48 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
05:59:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-nop_8f-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
05:59:48 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))
05:59:48 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
05:59:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-al1v4v-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
05:59:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///C:/slave/test/build/tests/mozbase/mozprocess in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 12))
05:59:48 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
05:59:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vwezaz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
05:59:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///C:/slave/test/build/tests/mozbase/mozprofile in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 13))
05:59:48 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
05:59:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xiplev-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
05:59:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///C:/slave/test/build/tests/mozbase/mozrunner in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 14))
05:59:48 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
05:59:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-abfswg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
05:59:49 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))
05:59:49 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
05:59:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gmgchj-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
05:59:49 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))
05:59:49 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
05:59:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lzwmrq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
05:59:49 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))
05:59:49 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))
05:59:49 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))
05:59:49 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))
05:59:49 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))
05:59:49 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))
05:59:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10))
05:59:49 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
05:59:49 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
05:59:50 INFO - Downloading blessings-1.6.tar.gz
05:59:50 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings
05:59:50 INFO - Installing collected packages: blessings
05:59:50 INFO - Running setup.py install for blessings
05:59:50 INFO - Successfully installed blessings
05:59:50 INFO - Cleaning up...
05:59:53 INFO - Return code: 0
05:59:53 INFO - Done creating virtualenv C:\slave\test\build/venv.
05:59:53 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
05:59:53 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
05:59:54 INFO - Reading from file tmpfile_stdout
05:59:54 INFO - Using _rmtree_windows ...
05:59:54 INFO - Using _rmtree_windows ...
05:59:54 INFO - Current package versions:
05:59:54 INFO - blessings == 1.6
05:59:54 INFO - blobuploader == 1.2.4
05:59:54 INFO - docopt == 0.6.1
05:59:54 INFO - functools32 == 3.2.3-2
05:59:54 INFO - jsonschema == 2.5.1
05:59:54 INFO - manifestparser == 1.1
05:59:54 INFO - mozInstall == 1.12
05:59:54 INFO - mozcrash == 0.17
05:59:54 INFO - mozdebug == 0.1
05:59:54 INFO - mozdevice == 0.48
05:59:54 INFO - mozfile == 1.2
05:59:54 INFO - mozhttpd == 0.7
05:59:54 INFO - mozinfo == 0.9
05:59:54 INFO - mozleak == 0.1
05:59:54 INFO - mozlog == 3.4
05:59:54 INFO - moznetwork == 0.27
05:59:54 INFO - mozprocess == 0.23
05:59:54 INFO - mozprofile == 0.28
05:59:54 INFO - mozrunner == 6.12
05:59:54 INFO - mozscreenshot == 0.1
05:59:54 INFO - mozsystemmonitor == 0.3
05:59:54 INFO - moztest == 0.7
05:59:54 INFO - mozversion == 1.4
05:59:54 INFO - psutil == 3.1.1
05:59:54 INFO - requests == 1.2.3
05:59:54 INFO - Installing None into virtualenv C:\slave\test\build/venv
05:59:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
05:59:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
05:59:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
05:59:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
05:59:54 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 0x018E8640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01922E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01862B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0193CE90>, '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 0x012AA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019A8520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-055', 'USERDOMAIN': 'T-W732-IX-055', '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-055', '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
05:59:54 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
05:59:54 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
05:59:54 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
05:59:54 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
05:59:54 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
05:59:54 INFO - 'COMPUTERNAME': 'T-W732-IX-055',
05:59:54 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
05:59:54 INFO - 'CYGWINBASE': 'C:\\cygwin',
05:59:54 INFO - 'DCLOCATION': 'SCL3',
05:59:54 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
05:59:54 INFO - 'FP_NO_HOST_CHECK': 'NO',
05:59:54 INFO - 'HOMEDRIVE': 'C:',
05:59:54 INFO - 'HOMEPATH': '\\Users\\cltbld',
05:59:54 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
05:59:54 INFO - 'KTS_VERSION': '1.19c',
05:59:54 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
05:59:54 INFO - 'LOGONSERVER': '\\\\T-W732-IX-055',
05:59:54 INFO - 'MONDIR': 'C:\\Monitor_config\\',
05:59:54 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
05:59:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
05:59:54 INFO - 'MOZ_NO_REMOTE': '1',
05:59:54 INFO - 'NO_EM_RESTART': '1',
05:59:54 INFO - 'NUMBER_OF_PROCESSORS': '8',
05:59:54 INFO - 'OS': 'Windows_NT',
05:59:54 INFO - 'OURDRIVE': 'C:',
05:59:54 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
05:59:54 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
05:59:54 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
05:59:54 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
05:59:54 INFO - 'PROCESSOR_LEVEL': '6',
05:59:54 INFO - 'PROCESSOR_REVISION': '1e05',
05:59:54 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
05:59:54 INFO - 'PROGRAMFILES': 'C:\\Program Files',
05:59:54 INFO - 'PROMPT': '$P$G',
05:59:54 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
05:59:54 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
05:59:54 INFO - 'PUBLIC': 'C:\\Users\\Public',
05:59:54 INFO - 'PWD': 'C:\\slave\\test',
05:59:54 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
05:59:54 INFO - 'SLAVEDIR': 'C:\\slave\\',
05:59:54 INFO - 'SYSTEMDRIVE': 'C:',
05:59:54 INFO - 'SYSTEMROOT': 'C:\\windows',
05:59:54 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
05:59:54 INFO - 'TEST1': 'testie',
05:59:54 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
05:59:54 INFO - 'USERDOMAIN': 'T-W732-IX-055',
05:59:54 INFO - 'USERNAME': 'cltbld',
05:59:54 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
05:59:54 INFO - 'WINDIR': 'C:\\windows',
05:59:54 INFO - 'WINDOWS_TRACING_FLAGS': '3',
05:59:54 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
05:59:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
05:59:54 INFO - Ignoring indexes: https://pypi.python.org/simple/
05:59:54 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))
05:59:54 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))
05:59:54 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))
05:59:54 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))
05:59:54 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))
05:59:54 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))
05:59:54 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))
05:59:54 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))
05:59:54 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))
05:59:54 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))
05:59:54 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))
05:59:54 INFO - Cleaning up...
05:59:54 INFO - Return code: 0
05:59:54 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv
05:59:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
05:59:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
05:59:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
05:59:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
05:59:54 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018E8640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01922E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01862B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0193CE90>, '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 0x012AA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019A8520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-055', 'USERDOMAIN': 'T-W732-IX-055', '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-055', '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
05:59:54 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in C:\slave\test\build
05:59:54 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema
05:59:54 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
05:59:54 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
05:59:54 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
05:59:54 INFO - 'COMPUTERNAME': 'T-W732-IX-055',
05:59:54 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
05:59:54 INFO - 'CYGWINBASE': 'C:\\cygwin',
05:59:54 INFO - 'DCLOCATION': 'SCL3',
05:59:54 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
05:59:54 INFO - 'FP_NO_HOST_CHECK': 'NO',
05:59:54 INFO - 'HOMEDRIVE': 'C:',
05:59:54 INFO - 'HOMEPATH': '\\Users\\cltbld',
05:59:54 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
05:59:54 INFO - 'KTS_VERSION': '1.19c',
05:59:54 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
05:59:54 INFO - 'LOGONSERVER': '\\\\T-W732-IX-055',
05:59:54 INFO - 'MONDIR': 'C:\\Monitor_config\\',
05:59:54 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
05:59:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
05:59:54 INFO - 'MOZ_NO_REMOTE': '1',
05:59:54 INFO - 'NO_EM_RESTART': '1',
05:59:54 INFO - 'NUMBER_OF_PROCESSORS': '8',
05:59:54 INFO - 'OS': 'Windows_NT',
05:59:54 INFO - 'OURDRIVE': 'C:',
05:59:54 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
05:59:54 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
05:59:54 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
05:59:54 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
05:59:54 INFO - 'PROCESSOR_LEVEL': '6',
05:59:54 INFO - 'PROCESSOR_REVISION': '1e05',
05:59:54 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
05:59:54 INFO - 'PROGRAMFILES': 'C:\\Program Files',
05:59:54 INFO - 'PROMPT': '$P$G',
05:59:54 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
05:59:54 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
05:59:54 INFO - 'PUBLIC': 'C:\\Users\\Public',
05:59:54 INFO - 'PWD': 'C:\\slave\\test',
05:59:54 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
05:59:54 INFO - 'SLAVEDIR': 'C:\\slave\\',
05:59:54 INFO - 'SYSTEMDRIVE': 'C:',
05:59:54 INFO - 'SYSTEMROOT': 'C:\\windows',
05:59:54 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
05:59:54 INFO - 'TEST1': 'testie',
05:59:54 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
05:59:54 INFO - 'USERDOMAIN': 'T-W732-IX-055',
05:59:54 INFO - 'USERNAME': 'cltbld',
05:59:54 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
05:59:54 INFO - 'WINDIR': 'C:\\windows',
05:59:54 INFO - 'WINDOWS_TRACING_FLAGS': '3',
05:59:54 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
05:59:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
05:59:54 INFO - Ignoring indexes: https://pypi.python.org/simple/
05:59:54 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages
05:59:54 INFO - Cleaning up...
05:59:54 INFO - Return code: 0
05:59:54 INFO - Running post-action listener: _resource_record_post_action
05:59:54 INFO - Running post-action listener: _start_resource_monitoring
05:59:54 INFO - Starting resource monitoring.
05:59:54 INFO - [mozharness: 2016-09-27 12:59:54.914000Z] Finished create-virtualenv step (success)
05:59:54 INFO - [mozharness: 2016-09-27 12:59:54.914000Z] Running install step.
05:59:54 INFO - Running pre-action listener: _resource_record_pre_action
05:59:54 INFO - Running main action method: install
05:59:54 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
05:59:54 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
05:59:55 INFO - Reading from file tmpfile_stdout
05:59:55 INFO - Using _rmtree_windows ...
05:59:55 INFO - Using _rmtree_windows ...
05:59:55 INFO - Detecting whether we're running mozinstall >=1.0...
05:59:55 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h']
05:59:55 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h
05:59:55 INFO - Reading from file tmpfile_stdout
05:59:55 INFO - Output received:
05:59:55 INFO - Usage: mozinstall-script.py [options] installer
05:59:55 INFO - Options:
05:59:55 INFO - -h, --help show this help message and exit
05:59:55 INFO - -d DEST, --destination=DEST
05:59:55 INFO - Directory to install application into. [default:
05:59:55 INFO - "C:\slave\test"]
05:59:55 INFO - --app=APP Application being installed. [default: firefox]
05:59:55 INFO - Using _rmtree_windows ...
05:59:55 INFO - Using _rmtree_windows ...
05:59:55 INFO - mkdir: C:\slave\test\build\application
05:59:55 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']
05:59:55 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
06:00:00 INFO - Reading from file tmpfile_stdout
06:00:00 INFO - Output received:
06:00:00 INFO - C:\slave\test\build\application\firefox\firefox.exe
06:00:00 INFO - Using _rmtree_windows ...
06:00:00 INFO - Using _rmtree_windows ...
06:00:00 INFO - Running post-action listener: _resource_record_post_action
06:00:00 INFO - [mozharness: 2016-09-27 13:00:00.518000Z] Finished install step (success)
06:00:00 INFO - [mozharness: 2016-09-27 13:00:00.518000Z] Running run-tests step.
06:00:00 INFO - Running pre-action listener: _resource_record_pre_action
06:00:00 INFO - Running main action method: run_tests
06:00:00 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version']
06:00:00 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version
06:00:00 INFO - Python 2.7.3
06:00:00 INFO - Return code: 0
06:00:00 INFO - grabbing minidump binary from tooltool
06:00: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')]}
06:00:00 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 0x0193CE90>, '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 0x012AA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019A8520>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1
06:00:00 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
06:00:00 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
06:00:00 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
06:00:00 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'...
06:00:01 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmpgjflq_
06:00:01 INFO - INFO - File integrity verified, renaming tmpgjflq_ to win32-minidump_stackwalk.exe
06:00:01 INFO - Return code: 0
06:00:01 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755
06:00:01 INFO - mkdir: C:\slave\test\build\blobber_upload_dir
06:00:01 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir
06:00:01 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe
06:00:01 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir
06:00:01 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YPQvFkwPSKusQtWjjH_XKQ/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-055', '--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
06:00:01 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/YPQvFkwPSKusQtWjjH_XKQ/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-055 --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
06:00:01 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
06:00:01 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
06:00:01 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
06:00:01 INFO - 'COMPUTERNAME': 'T-W732-IX-055',
06:00:01 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
06:00:01 INFO - 'CYGWINBASE': 'C:\\cygwin',
06:00:01 INFO - 'DCLOCATION': 'SCL3',
06:00:01 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
06:00:01 INFO - 'FP_NO_HOST_CHECK': 'NO',
06:00:01 INFO - 'HOMEDRIVE': 'C:',
06:00:01 INFO - 'HOMEPATH': '\\Users\\cltbld',
06:00:01 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
06:00:01 INFO - 'KTS_VERSION': '1.19c',
06:00:01 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
06:00:01 INFO - 'LOGONSERVER': '\\\\T-W732-IX-055',
06:00:01 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir',
06:00:01 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe',
06:00:01 INFO - 'MONDIR': 'C:\\Monitor_config\\',
06:00:01 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
06:00:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
06:00:01 INFO - 'MOZ_NO_REMOTE': '1',
06:00:01 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir',
06:00:01 INFO - 'NO_EM_RESTART': '1',
06:00:01 INFO - 'NUMBER_OF_PROCESSORS': '8',
06:00:01 INFO - 'OS': 'Windows_NT',
06:00:01 INFO - 'OURDRIVE': 'C:',
06:00: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;',
06:00:01 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
06:00:01 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
06:00:01 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
06:00:01 INFO - 'PROCESSOR_LEVEL': '6',
06:00:01 INFO - 'PROCESSOR_REVISION': '1e05',
06:00:01 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
06:00:01 INFO - 'PROGRAMFILES': 'C:\\Program Files',
06:00:01 INFO - 'PROMPT': '$P$G',
06:00:01 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
06:00:01 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
06:00:01 INFO - 'PUBLIC': 'C:\\Users\\Public',
06:00:01 INFO - 'PWD': 'C:\\slave\\test',
06:00:01 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos',
06:00:01 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
06:00:01 INFO - 'SLAVEDIR': 'C:\\slave\\',
06:00:01 INFO - 'SYSTEMDRIVE': 'C:',
06:00:01 INFO - 'SYSTEMROOT': 'C:\\windows',
06:00:01 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:00:01 INFO - 'TEST1': 'testie',
06:00:01 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
06:00:01 INFO - 'USERDOMAIN': 'T-W732-IX-055',
06:00:01 INFO - 'USERNAME': 'cltbld',
06:00:01 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
06:00:01 INFO - 'WINDIR': 'C:\\windows',
06:00:01 INFO - 'WINDOWS_TRACING_FLAGS': '3',
06:00:01 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
06:00:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
06:00:01 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YPQvFkwPSKusQtWjjH_XKQ/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-055', '--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
06:00:02 INFO - mozversion application_buildid: 20160927033006
06:00:02 INFO - mozversion application_changeset: cb212903f7036117ddbb29b3bdbc390b161b1bb2
06:00:02 INFO - mozversion application_display_name: Nightly
06:00:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
06:00:02 INFO - mozversion application_name: Firefox
06:00:02 INFO - mozversion application_remotingname: firefox
06:00:02 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team
06:00:02 INFO - mozversion application_vendor: Mozilla
06:00:02 INFO - mozversion application_version: 52.0a1
06:00:02 INFO - mozversion platform_buildid: 20160927033006
06:00:02 INFO - mozversion platform_changeset: cb212903f7036117ddbb29b3bdbc390b161b1bb2
06:00:02 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team
06:00:02 INFO - mozversion platform_version: 52.0a1
06:00:02 INFO - using testdate: 1474981202
06:00:02 INFO - actual date: 1474981202
06:00:02 INFO - starting webserver on 'localhost:49613'
06:00:02 INFO - SUITE-START | Running 2 tests
06:00:02 INFO - TEST-START | dromaeo_css
06:00:02 INFO - operating with platform_type : w7_
06:00:02 INFO - Initialising browser for dromaeo_css test...
06:00:03 INFO - TEST-INFO | started process 3672 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpde0p5o\profile http://localhost:49613/getInfo.html)
06:00:10 INFO - PROCESS | 3672 | __metrics Screen width/height:1600/1200
06:00:10 INFO - PROCESS | 3672 | colorDepth:24
06:00:10 INFO - PROCESS | 3672 | Browser inner width/height: 1010/674
06:00:10 INFO - PROCESS | 3672 | __metrics
06:00:11 INFO - PROCESS | 3672 | 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"
06:00:11 INFO - PROCESS | 3672 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
06:00:12 INFO - TEST-INFO | 3672: exit 0
06:00:12 INFO - Browser initialized.
06:00:12 INFO - Running cycle 1/1 for dromaeo_css test...
06:00:12 INFO - TEST-INFO | started process 3904 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpde0p5o\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1)
06:00:13 INFO - PROCESS | 3904 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0
06:00:24 INFO - PROCESS | 3904 | RSS: Main: 124211200
06:00:24 INFO - PROCESS | 3904 |
06:01:15 INFO - PROCESS | 3904 | 1474981275407 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]
06:03:24 INFO - PROCESS | 3904 | Cycle 1(1): loaded http://localhost:49613/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49613/tests/dromaeo/cssquery-ext.html)
06:03:25 INFO - PROCESS | 3904 | RSS: Main: 213458944
06:03:25 INFO - PROCESS | 3904 |
06:06:28 INFO - PROCESS | 3904 | Cycle 1(1): loaded http://localhost:49613/tests/dromaeo/cssquery-ext.html (next: http://localhost:49613/tests/dromaeo/cssquery-jquery.html)
06:06:28 INFO - PROCESS | 3904 | RSS: Main: 197943296
06:06:28 INFO - PROCESS | 3904 |
06:09:30 INFO - PROCESS | 3904 | Cycle 1(1): loaded http://localhost:49613/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49613/tests/dromaeo/cssquery-mootools.html)
06:09:31 INFO - PROCESS | 3904 | RSS: Main: 154132480
06:09:31 INFO - PROCESS | 3904 |
06:12:33 INFO - PROCESS | 3904 | Cycle 1(1): loaded http://localhost:49613/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49613/tests/dromaeo/cssquery-prototype.html)
06:12:34 INFO - PROCESS | 3904 | RSS: Main: 141496320
06:12:34 INFO - PROCESS | 3904 |
06:15:36 INFO - PROCESS | 3904 | Cycle 1(1): loaded http://localhost:49613/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49613/tests/dromaeo/cssquery-yui.html)
06:15:37 INFO - PROCESS | 3904 | RSS: Main: 136085504
06:15:37 INFO - PROCESS | 3904 |
06:18:40 INFO - PROCESS | 3904 | Cycle 1(1): loaded http://localhost:49613/tests/dromaeo/cssquery-yui.html (next: http://localhost:49613/tests/dromaeo/cssquery-dojo.html)
06:18:40 INFO - PROCESS | 3904 | RSS: Main: 189771776
06:18:40 INFO - PROCESS | 3904 |
06:18:40 INFO - PROCESS | 3904 | __start_tp_report
06:18:40 INFO - PROCESS | 3904 | _x_x_mozilla_page_load
06:18:40 INFO - PROCESS | 3904 | _x_x_mozilla_page_load_details
06:18:40 INFO - PROCESS | 3904 | |i|pagename|runs|
06:18:40 INFO - PROCESS | 3904 | |0;dojo.html;2780.2354352553048;2797.9647530413736;2809.1993781772194;2818.2672505148666;2821.5485522316408;4752.025834703886;4783.330333753273;4796.16067188242;4868.683535570185;4874.951250487498;4728.338032675429;4762.071396077767;4813.350197723306;4816.566509014188;4890.168671325874;4222.239996800575;4236.131593023439;4257.04216551275;4270.893227669302;4278.8502402415925;4211.978940105295;4227.725197862144;4235.8093885775215;4304.225239456897;4355;4251.043515209084;4338.587834155766;4338.717983331074;4348.391225228471;4351.956480435203;4145.7719825409595;4206.831726730927;4219.852305169305;4232.978835105805;4308.138372325548;4129.463169787939;4160.750354978711;4195.895102622429;4196.895077623084;4297.8495752648505;4752.809887604491;4762.071396077767;4839.37088178538;4854.49027852074;4872.342233798463;4901.681390709592;4952.281919121743;4985.1774457214515;5055.342805435306;5130.3074084998425;5112.36095488064;5187.5071868172845;5198.142306519419;5202.115640341151;5219.8434046978655;4701.694389864648;4731.668783185179;4826.251930950708;4829.1790395632825;4832.73419961902;4971.5028497150715;5062.696238225718;5187.325647665779;5223.738813059408;5323.920141197885;4999.65002449825;5042.344495215599;5106.412762532282;5113.054084994288;5114.590832733373;5041.949580504222;5107.974460127675;5212.713300768418;5216.26972223889;5218.8956220876125;4928.236123400879;4954.479779623088;5076.974615126975;5102.005109003783;5176.171812510056;4970.15507363749;4998.575121114746;5006.499350065037;5028.572571331405;5041.193409054607;3344.1305260631925;3392.2537041850755;3396.1170095774783;3410.6248312685584;3448.5689288838894;3119.235787232097;3155.984220078885;3175.1268401189395;3180.9522857157162;3183.681631836798;4925.064237794808;4931.087748766489;5012.598992080625;5023.1711767557945;5029.899402011939;4849.660523763303;4858.58702010326;5051.191809310492;5065.696058236517;5069.214271787879;4866.975665121652;4867.464578896319;4870.829520966749;4872.537108974641;4879.926801097987;4180.874573762792;4224.577542245751;4225.894352641147;4235.174141042528;4243.554426785205;5374.220737992969;5385.542228910508;5407.161889907071;5407.83776486706;5407.891842163135;5072.594192464668;5105.795768169231;5126.000429916131;5130.358705161855;5140.742962851918;5443.537299329443;5454;5459.099248624091;5466.863328416822;5479.726013699379;4839.758012099452;5017.3226614406585;5071.39143302806;5129.153689641316;5147.845564633067;5085;5148.613853961043;5200.375954885438;5293.285406470077;5305.204219366971;4811.663183577116;4851.442084160367;4902.950970490249;5006.7246301452315;5122.359705036871;4956.306117143675;4964.379452568429;4983.401991761012;5066.164082926276;5066.949330506648;4807.831725889577;4902.019596080727;5004.224345226496;5008.223725322581;5013.373328333958;4838.419389673121;4925.187344088328;5018.071656743514;5019.32239147725;5127.333446651912;4952.1828898232825;4967.105920934122;5036.597072234285;5047.747612619428;5051.393832739947
06:18:40 INFO - PROCESS | 3904 | |1;ext.html;513.6250537107911;552.2627292564426;556.6966003528076;569.8034178208519;577.2985822226;29038.128856134324;30258;31547.369052618935;31558.84220578897;42589.57410425895;42925.35611965823;43416.78291608537;43482;43578.346324805156;43581.34627980583;8507.404481686284;8509.319254459644;8679.609417576192;8683.826323473526;8694.304455643532;8052.315553177987;8218.917810821875;8483.600205966039;8629.69796057138;8642.962844458674;8287.62705678246;8508.914910850905;8615.870761938577;8682.956585217065;8684.696035638754;8602.612882420306;8612.52631105289;8621.396502244845;8629.266512346458;8644.740657780276;8555.486670799772;8610.095939926343;8638.697645582437;8638.956805215996;8649.524276164871;40047.599524004676;40462;40473.79763101166;40527;40545.797271013755;3029.5758593796886;3096.6284045914635;3125.8749650013974;3129.9530507042414;3173.809571425722;30620.081397558108;31095.84452077748;31438.528422073687;31908.70196764726;32259.19352016195;3549.893503194908;3582.8208589570677;3593.4138918902813;3604.981975090134;3636.5636123665063;380718;383443;383736.0813195944;385044;385659.07943348057;50609.493905061216;51452.48547514477;53166.468335316924;53246.467535324926;53818.192727109126;27127.593086103723;27598.44803103927;28431.857840711076;54947.72526137344;55544.72227638836;14217.85782142186;14324.212168330634;14379.065360751518;14483.927580362242;15239.619009524851;11903.285802851857;11946.402679865972;11946.940265298617;11991.160618756603;12033.338166400757;17881.642367152584;17897.910510447626;18031.188596513188;18052.729209061876;18065.638687226445;6829.419499342612;6888.4781883122905;6920.861582768417;6952.026716259727;7046.577205367695;766.4443278623065;844.160060739568;857.7469646454311;902.9548522573845;941.7833898203452;27949.860250698617;28702.569461458093;28998.710012899603;29013.56479652807;29462.558061629094;866.7269810009826;886.1138861138861;914.5153068873508;935.6958988328685;967.8064387122604;14190.85809141916;14271.57185284443;14490.275486225646;14747.483838065617;15098.849011509745;540915;541445.5855441395;542886;554401;580569;51305.7434712824;51428.22857657138;51536.22695659568;51681.48318516842;51747.74126129345;1018.9184865210765;1041.6822869024868;1116.20749268019;1131.8924702153283;1148.8851114888612;28403.857980709963;28572.142835714963;28964.565531516622;29062.41875162527;29208.26979325534;43107;44453.66639000835;44894.551054490345;45984;46042.07915841731;444222;454637.90215734614;459517;463880;464397;19787.20851165995;20092.39722808318;21504.354869353945;22389.32832015042;23043.193488227833;42222.57777422186;42354.78822605867;43716.781416092716;44006.11987760291;44172.337414938156;35935.64064359323;38385.23229535449;39210.803945980086;39639.40540891831;40131.398029029006;37266.8136659315;38534.6146538531;38566.42150367803;38675.806620966716;39146.608533914296;36472.81763591271;36628.267434651694;36773.63226367702;36797.44803827998;36849.81575092107;33236.83381583077;33885.15287117842;34215.828920856235;34683.30633387368;35530.28939421249;25681.22956311314;25740.742592573835;25820.870895646152;25822.741772582034;25876.353091172117
06:18:40 INFO - PROCESS | 3904 | |2;jquery.html;272.09799514609057;288.86712112428285;300.9262730630996;303.81163678519323;307.28096254763864;12927.612171634853;17327.22027508762;17810.732839007425;17837.37569185077;19226.13482393292;16871.325146994135;17471.91264043678;17478.737818932696;17511.211995460202;17603.11984400778;5242.6330156889035;5315.760790764415;5476.849861529084;5536.474034966672;5551.3893471718075;4929.112759703252;5216.191490319006;5470.124780035195;5472.863178420552;5502.614816962794;5189.688618682872;5447.346318441792;5448.346198456191;5522.392536820947;5537.390887002447;5169.457206993268;5208.973955130219;5346.064438723227;5366.953444078406;5367.785288588452;5068.315777370045;5114;5169.379674439054;5207.0366982108435;5212.7914883405;11449.370284634342;16797.244124014447;21926.671099933516;22288.442788930246;22501.887490562607;1890.3667271464076;2004.2484068474323;2037.7388440246912;2369.052379048377;2618.751218634227;8779.29765618749;8795.296376289882;8950.776230594221;8971.685990990349;9006.954965225197;1516.673915108246;1633.338497908349;1683.8231985641453;1687.6118492746614;1736.696078186325;86870.09084591208;89182.55408722915;91033.54483227608;91537.54231228867;92729;8761.737147885573;8851.33614978879;9182.403143795633;9230.261579073793;9332.206762425134;10764.085052770602;10957.342559446459;10968.396738179477;11284.492197851116;11296.152788540892;7083.397911177608;7173.92826071743;7448.9627551861895;7451.590162541004;7550.207228241065;7619.847603047908;7656.3874890008665;7674.424418168548;7827.1390147083775;7838;3670.6145854685406;3687.815609219528;3739.6447337502896;3789.07167743899;3811.2568049230135;1522.9543113706384;1533.4786172701113;1594.6013496625844;1603.2384617306918;1604.189884108541;834.3241974001079;837.225082824404;901.5402144906145;912.1471424218377;921.8663293822492;17769.07202070717;18225.544361391072;18245.452636420927;18457.261709531736;18880.716789248174;810.6190090657382;832.0847068224904;851.565701492231;866.9349798765118;874.8993865705398;9726.798873644417;10014.39913605186;10107;10321.741956450998;10327.84508232377;457645;458410;459639;473130;473936;87265.56367218123;97232.51383743036;97456;97706;101264.45574404861;814.995925020383;841.4782834642439;851.3487182305417;854.6495936665937;855.7732200966749;16425.589360266087;16613.3354665812;16853.578660533585;16929.66140677155;17335.306587736854;46227.3065904005;47060.52939470561;47449.05101897874;47644.7617761909;48433.75783121062;65681;66367.33632663611;66459;66961;67925;6071.694585664103;6676.365745254193;6814.488913331421;6819.931800682129;7312.670929808065;8435.95782021086;12496.312702801433;12850.35748212567;12951.676208094874;13338.666533336744;10615.734606634896;10865.728356791144;11227.165785017498;11546.0763138947;11638.185327027028;11163.274387164809;11258.493367798632;11399.601013964471;11492.655220343404;11683.474243658886;11145.331280123219;11220.270682405617;11497.48261328259;11792.587259445878;11866.347350895783;7166.355028047501;7376.7418140364825;7433.553986760811;7570.242975702386;7833.060032796101;8216;10279.640212592523;10709.250352475252;10829.754578223605;11240.887591123985
06:18:40 INFO - PROCESS | 3904 | |3;mootools.html;1048.8951104889506;1180.3803003423204;1181.4565299962017;1251.3618054792057;1552.5109590479005;5389.353277606688;6957.408620267277;7152.4278057755255;7160.391366733821;7292.416606671467;7165.570065796056;7230;7233.602151881644;7240.529365591233;7246.963765181166;1846.7876194237633;1857.5077604434834;1863.1336428560717;1871.9438416847515;1879.8308152266293;1828.552004758836;1866.906654667268;1910.8949007804565;1918.290232613935;1921.2219051284253;1788.3561917709615;1886.8679192456534;1903.5621806984402;1907.2275728330048;1910.5414700471856;1700.387860370272;1709.734991076379;1800.79290881549;1807.033237218095;1807.0422675981752;1829.1951541321775;1843.1060935446299;1847.8891266523917;1849.066221558118;1852.2220667319757;6823.761168359084;6985.9042287313605;7039.577625342496;7077.115360579927;7118.359347658736;4065.5527891931865;4070.9185816283507;4118.4440100586335;4311.698181127323;4320.984568626371;2745.2999485131363;2821.44981728563;2868.670102938168;2870.038537090077;2875.62616859809;812.4556547113448;835.9038710548303;844.5312851367488;846.1327139681814;847.576211894053;8559;8721.563921803885;8741.825163496758;9011.45931244121;9032.09679032098;3296.93406131876;3358.429067058606;3400.880969166067;3453.740969427303;3521.3837578423672;7028.508004439743;7238.0952380952385;7270.927290727131;7272.309130632581;7276.308750668783;3845.615438456132;4099.8975025624595;4153.190127925086;4159.396887451363;4177.060161463707;3418.435958066891;3520.8767693130617;4006.599340066028;4011.498562679665;4017.517897852218;2766.5435203191246;2992.521196608576;3004.7145521175453;3014.080705384835;3045.25391279138;839.7564706235246;894.6868595991481;898.7573355194061;900.8603666431713;906.3474298505066;709.6593635055099;745.1021519069508;747.1594456236734;752.6613024139159;789.5105034878412;4452.666050046259;4512.909741805145;4541.5231400703115;4547.8635640930825;4571.6799824012;775.5463054113407;780.8165081206025;788.3417346515723;805.7824387415365;813.0730966697969;3144.5912031436224;3148.9055328340187;3149.4960806270888;3149.7952633078776;3183.8726450942168;8417.789555261044;8527.701530446404;8753.168448997336;8789.868151977726;8811.603477843513;8230.917690823135;8358.916410835815;8383.874241886377;8800.295976322006;8807.33944954131;6456.451201647818;6492.610443373318;6958.512904096766;6958.608278344352;6959.060526828915;4402.8679139625865;4562.384078149407;4636.44362676481;4643.978324768681;4683.718976861399;7544.811379715551;8120.715774948085;8189.140140285184;8206.958965205136;8232.464889782144;8243.793905152419;8297.211764882326;8298.751037468644;8335.624896879775;8352.248297653241;5400.135978243462;5413.83758487246;5665.405132461032;5734.2258795062135;5740.856478587902;6401.615903045832;6569.540132190701;6696.7656132035145;6943.51395402317;7001.229864714775;6176.4441200292185;6183.412575805291;6334.8733025338315;6364.840878978062;6397.744090236339;5827.358990510956;6091.613559524864;6139.447449729546;6164.907526387197;6193.504519638508;5755.482006619424;5777.74000169985;5782.306123265235;5809.50619197373;5813.3895940925595;3757.4927384803796;3777.301199278142;3851.7496362736333;3932.8230229640308;4013.658838998718;3883.126296583246;4111.588841115865;4129.339305711072;4163.562825903236;4179.958200418078
06:18:40 INFO - PROCESS | 3904 | |4;prototype.html;238.35881478821958;243.91828737372978;247.71389045652268;248.25647186675903;249.6654482992788;4981.078500477414;5081.314022606947;5106.948930510689;5164.741762911858;5172.508611681894;4987.576056035232;5050.772715227814;5093.949060509403;5111.105556527611;5121.206213036976;3581.874634387797;3721.441783732448;3763.7929913854728;3767.7550959187633;3776.848926042955;3558.8754393596228;3660.963390366089;3686.944695829551;3772.0192749884955;3779.111908701453;3703.333399988001;3720.5721342045604;3725.068732816796;3730.2166545025575;3741.2517496500673;3639.490471334015;3676.6323367663285;3714.0872450687916;3723.1250656095795;3728.589855115962;3082.291073053188;3140.1835522764018;3219.4848824188252;3220.21104829316;3232.789868658553;4754.857354279377;4852.636052296057;4928.975355123237;4992.650514463989;5018.272350509193;4108.8356465741335;4318.272690923624;4369.890752731207;4375.474943006828;4381.9123617527475;5113.2330150477355;5247.16045432729;5282.656627319212;5297.417284098746;5328.92006619901;3360.5631267935255;3430.5711786026745;3578.1054736315923;3582.8925132245813;3595.514605528246;5425.755840987165;5437.782488700447;5492.615516913817;5540.556755459594;5563.7774489019985;3500.2474467989505;3541.1501239702284;3548.8225588720975;3558.8255875561003;3566.144125409935;3557.0258506529126;3599.856005759793;3607;3607.062163837421;3611.3499570077115;4047.736897101679;4049.4735684361435;4260.510041345223;4280.914381712349;4287.7641729705165;5163.251328557338;5183.688978661293;5378.596605254544;5403.70279634616;5416.0792665247;1178.7112157521462;1198.083466148397;1205.3430880170329;1229.59423390281;1229.674136353867;764.2472164917551;767.7351313796731;774.4462709162863;780.4965797060977;797.6490344248512;4966.329545511309;5179.145441002268;5186.222066689951;5189.273501709764;5201.245819356172;5379.515843574097;5459.727013649302;5479.479439457894;5490.995786537885;5505.761203729129;5139.58883289336;5204.323437953042;5214.400343960418;5217.947820521822;5223.033738758342;2503.336615796779;2581.535323641725;2602.3103877472486;2617.411082506421;2635.61630144172;5151.356080489939;5200.323957885451;5204.843854684365;5214.921776173361;5231.3199284092825;4882.827141921811;4950.4059512858685;4991.301217829507;5028.321176641107;5041.7227052511735;5215.634905556574;5228.398734145546;5236.947630523722;5262.578993680497;5292.894142117213;4679.338834713628;4913.263010548446;4919.819243381634;4927.975360123177;4928.373636699809;4795.544423279783;4822.614190864652;4841.201201801804;4866.172750632471;4889.535494128052;5204.58363330925;5208.713520756395;5210.713410762292;5230.201842014504;5246.947530524646;3284.2282063715484;3289.259916518764;3300.5214243934975;3300.603927528712;3301.7523685723186;4995.425526064404;5023.572996295356;5598.300212473441;5625.634333768456;5626.831195064155;5188.469401526483;5312.4156342803035;5315.04329220744;5327.786888524415;5333.933213357266;5137.9486205138965;5301.019311427398;5410.3507579090765;5421.322334708161;5424.918626220689;5140.177571588528;5242.475752424727;5272.894542109059;5275.076861549291;5285.524302812765;5128.205128205134;5383.246345511553;5407.351117865881;5416.783328666967;5425.755840987086;5214.8957020860125;5270.367555893317;5328.973355133199;5331.466853314638;5334.439883812144
06:18:40 INFO - PROCESS | 3904 | |5;yui.html;617.4350469320569;674.5379415100654;685.3626127701239;696.0325148044218;709.2552819539485;15078.246087695627;15835.762463563056;15910;15911.761323580156;16120.435784747537;14194.29028548571;14291.356888940023;14505.202213878232;14522.056066355679;14550.563483095497;505.9848204553869;628.2523796681957;639.891218492855;640.4108220437186;645.5029627187063;531.6437986549003;627.9403456671632;640.5388120553195;642.351225262486;650.5023656902473;666.9599824010575;668.6422763821356;673.9224989126265;678.9592624442524;680.0377465885766;525.9027079990215;665.620596260132;666.0275997044273;666.6533402630629;689.7620320989275;409.4820052633421;474.43542184799975;488.22859881387325;489.7159647404497;502.51507295460056;12329.630111096682;13058.216507009514;13062.738745225042;13095.34523273842;13117.409716562777;12738.044646651446;12986.870131298756;13086.607401777961;13236.073474856765;13255.204687718768;12640.178388404816;12944.546940857024;13257.933710331483;13270.336483175803;13688.52090176849;4107.404426358161;4348.695591308609;4394.934075988863;4395.714278571914;4413.514513403523;85008;86114.5694271531;87910.56044719799;88127.55936220342;88597.55701221453;9890.406575605342;10146.847797283046;10493.317934334244;10552.683419497427;10691.305065170895;16397.180140992903;19730.60538789245;19734.506637334183;19771.110300036533;19794.20823167086;7368.521046131984;7555.73554925586;7950.204979502004;8013.158618345106;8211.21993410625;4205.453291072141;4327.718698284664;4510.639148868083;4512.503624601291;4527.275635898306;2724.1282789507563;2768.43247134337;2813.620161278242;2946.528555431121;2983.4480621085168;538.251829956361;539.7409243563112;541.6208653942256;541.6316904504896;548.9423610520918;627.4635186915214;657.9868402631921;686.2485578291762;697.9267176946347;704.327367364166;9970.800583988279;10018.39889606626;10362.326448780805;10403.791924161475;10413.323133996266;550.2571528436658;550.4413020783908;580.0805722929205;669.9866002679919;688.2498077095989;4655.231886738718;4765.642576806753;4801.7995501124715;4888.951110488921;4901.215805471107;67179;69035;83541.58229208815;123417.38291308486;126895;81507.59246203731;82596;84197;84901.57549212214;89303;71674;83331.58334208411;86114;87529;89615.55192224127;9333.300002499975;9359.906400935903;9679.66121185755;9717.17404020666;9981.950090249502;50057.749711251214;50445;50563.24155137602;50798.492015080854;51822;67564.6621766888;70144.64927675329;70828.29171708217;70961;71201;12792.232466052066;12956.870431295943;13002.284874332003;13055.738885222432;13459.730805384033;15452.459163929208;15542.5337239883;15695.686086277981;16211.837881621033;16217.18914054269;14099.365528551445;14140.292985350898;14405;14720.484783032542;14989.475368362055;13926.651833704238;14105.365258563184;14798;15067.623309417353;15178.165200914056;13088.952883769085;13341.266230357423;13520.729585408433;13540.526081587097;13722.313884305546;11750.648675402097;11955.581554645545;12080.477077307003;12374.319412432042;12478.812817807922;11216.943915280372;12384.31886246229;12419.627411177678;12471.438785254504;12481.001519878206
06:18:40 INFO - PROCESS | 3904 | __end_tp_report
06:18:40 INFO - PROCESS | 3904 | __start_cc_report
06:18:40 INFO - PROCESS | 3904 | _x_x_mozilla_cycle_collect,997
06:18:40 INFO - PROCESS | 3904 | __end_cc_report
06:18:40 INFO - PROCESS | 3904 | __startTimestamp1474982320452__endTimestamp
06:18:40 INFO - PROCESS | 3904 |
06:18:40 INFO - PROCESS | 3904 | ------- Summary: start -------
06:18:40 INFO - PROCESS | 3904 | Number of tests: 6
06:18:40 INFO - PROCESS | 3904 |
06:18:40 INFO - PROCESS | 3904 | [#0] dojo.html Cycles:165 Average:4716.67 Median:4928.24 stddev:613.31 (12.4%) stddev-sans-first:596.08
06:18:40 INFO - PROCESS | 3904 | Values: 2780.2 2798.0 2809.2 2818.3 2821.5 4752.0 4783.3 4796.2 4868.7 4875.0 4728.3 4762.1 4813.4 4816.6 4890.2 4222.2 4236.1 4257.0 4270.9 4278.9 4212.0 4227.7 4235.8 4304.2 4355.0 4251.0 4338.6 4338.7 4348.4 4352.0 4145.8 4206.8 4219.9 4233.0 4308.1 4129.5 4160.8 4195.9 4196.9 4297.8 4752.8 4762.1 4839.4 4854.5 4872.3 4901.7 4952.3 4985.2 5055.3 5130.3 5112.4 5187.5 5198.1 5202.1 5219.8 4701.7 4731.7 4826.3 4829.2 4832.7 4971.5 5062.7 5187.3 5223.7 5323.9 4999.7 5042.3 5106.4 5113.1 5114.6 5041.9 5108.0 5212.7 5216.3 5218.9 4928.2 4954.5 5077.0 5102.0 5176.2 4970.2 4998.6 5006.5 5028.6 5041.2 3344.1 3392.3 3396.1 3410.6 3448.6 3119.2 3156.0 3175.1 3181.0 3183.7 4925.1 4931.1 5012.6 5023.2 5029.9 4849.7 4858.6 5051.2 5065.7 5069.2 4867.0 4867.5 4870.8 4872.5 4879.9 4180.9 4224.6 4225.9 4235.2 4243.6 5374.2 5385.5 5407.2 5407.8 5407.9 5072.6 5105.8 5126.0 5130.4 5140.7 5443.5 5454.0 5459.1 5466.9 5479.7 4839.8 5017.3 5071.4 5129.2 5147.8 5085.0 5148.6 5200.4 5293.3 5305.2 4811.7 4851.4 4903.0 5006.7 5122.4 4956.3 4964.4 4983.4 5066.2 5066.9 4807.8 4902.0 5004.2 5008.2 5013.4 4838.4 4925.2 5018.1 5019.3 5127.3 4952.2 4967.1 5036.6 5047.7 5051.4
06:18:40 INFO - PROCESS | 3904 |
06:18:40 INFO - PROCESS | 3904 | [#1] ext.html Cycles:180 Average:59559.09 Median:39843.50 stddev:125063.99 (313.9%) stddev-sans-first:125336.25
06:18:40 INFO - PROCESS | 3904 | Values: 513.6 552.3 556.7 569.8 577.3 29038.1 30258.0 31547.4 31558.8 42589.6 42925.4 43416.8 43482.0 43578.3 43581.3 8507.4 8509.3 8679.6 8683.8 8694.3 8052.3 8218.9 8483.6 8629.7 8643.0 8287.6 8508.9 8615.9 8683.0 8684.7 8602.6 8612.5 8621.4 8629.3 8644.7 8555.5 8610.1 8638.7 8639.0 8649.5 40047.6 40462.0 40473.8 40527.0 40545.8 3029.6 3096.6 3125.9 3130.0 3173.8 30620.1 31095.8 31438.5 31908.7 32259.2 3549.9 3582.8 3593.4 3605.0 3636.6 380718.0 383443.0 383736.1 385044.0 385659.1 50609.5 51452.5 53166.5 53246.5 53818.2 27127.6 27598.4 28431.9 54947.7 55544.7 14217.9 14324.2 14379.1 14483.9 15239.6 11903.3 11946.4 11946.9 11991.2 12033.3 17881.6 17897.9 18031.2 18052.7 18065.6 6829.4 6888.5 6920.9 6952.0 7046.6 766.4 844.2 857.7 903.0 941.8 27949.9 28702.6 28998.7 29013.6 29462.6 866.7 886.1 914.5 935.7 967.8 14190.9 14271.6 14490.3 14747.5 15098.8 540915.0 541445.6 542886.0 554401.0 580569.0 51305.7 51428.2 51536.2 51681.5 51747.7 1018.9 1041.7 1116.2 1131.9 1148.9 28403.9 28572.1 28964.6 29062.4 29208.3 43107.0 44453.7 44894.6 45984.0 46042.1 444222.0 454637.9 459517.0 463880.0 464397.0 19787.2 20092.4 21504.4 22389.3 23043.2 42222.6 42354.8 43716.8 44006.1 44172.3 35935.6 38385.2 39210.8 39639.4 40131.4 37266.8 38534.6 38566.4 38675.8 39146.6 36472.8 36628.3 36773.6 36797.4 36849.8 33236.8 33885.2 34215.8 34683.3 35530.3 25681.2 25740.7 25820.9 25822.7 25876.4
06:18:40 INFO - PROCESS | 3904 |
06:18:40 INFO - PROCESS | 3904 | [#2] jquery.html Cycles:180 Average:28249.78 Median:47546.91 stddev:77361.49 (162.7%) stddev-sans-first:77549.99
06:18:40 INFO - PROCESS | 3904 | Values: 272.1 288.9 300.9 303.8 307.3 12927.6 17327.2 17810.7 17837.4 19226.1 16871.3 17471.9 17478.7 17511.2 17603.1 5242.6 5315.8 5476.8 5536.5 5551.4 4929.1 5216.2 5470.1 5472.9 5502.6 5189.7 5447.3 5448.3 5522.4 5537.4 5169.5 5209.0 5346.1 5367.0 5367.8 5068.3 5114.0 5169.4 5207.0 5212.8 11449.4 16797.2 21926.7 22288.4 22501.9 1890.4 2004.2 2037.7 2369.1 2618.8 8779.3 8795.3 8950.8 8971.7 9007.0 1516.7 1633.3 1683.8 1687.6 1736.7 86870.1 89182.6 91033.5 91537.5 92729.0 8761.7 8851.3 9182.4 9230.3 9332.2 10764.1 10957.3 10968.4 11284.5 11296.2 7083.4 7173.9 7449.0 7451.6 7550.2 7619.8 7656.4 7674.4 7827.1 7838.0 3670.6 3687.8 3739.6 3789.1 3811.3 1523.0 1533.5 1594.6 1603.2 1604.2 834.3 837.2 901.5 912.1 921.9 17769.1 18225.5 18245.5 18457.3 18880.7 810.6 832.1 851.6 866.9 874.9 9726.8 10014.4 10107.0 10321.7 10327.8 457645.0 458410.0 459639.0 473130.0 473936.0 87265.6 97232.5 97456.0 97706.0 101264.5 815.0 841.5 851.3 854.6 855.8 16425.6 16613.3 16853.6 16929.7 17335.3 46227.3 47060.5 47449.1 47644.8 48433.8 65681.0 66367.3 66459.0 66961.0 67925.0 6071.7 6676.4 6814.5 6819.9 7312.7 8436.0 12496.3 12850.4 12951.7 13338.7 10615.7 10865.7 11227.2 11546.1 11638.2 11163.3 11258.5 11399.6 11492.7 11683.5 11145.3 11220.3 11497.5 11792.6 11866.3 7166.4 7376.7 7433.6 7570.2 7833.1 8216.0 10279.6 10709.3 10829.8 11240.9
06:18:40 INFO - PROCESS | 3904 |
06:18:40 INFO - PROCESS | 3904 | [#3] mootools.html Cycles:180 Average:4529.99 Median:5748.17 stddev:2560.49 (44.5%) stddev-sans-first:2554.31
06:18:40 INFO - PROCESS | 3904 | Values: 1048.9 1180.4 1181.5 1251.4 1552.5 5389.4 6957.4 7152.4 7160.4 7292.4 7165.6 7230.0 7233.6 7240.5 7247.0 1846.8 1857.5 1863.1 1871.9 1879.8 1828.6 1866.9 1910.9 1918.3 1921.2 1788.4 1886.9 1903.6 1907.2 1910.5 1700.4 1709.7 1800.8 1807.0 1807.0 1829.2 1843.1 1847.9 1849.1 1852.2 6823.8 6985.9 7039.6 7077.1 7118.4 4065.6 4070.9 4118.4 4311.7 4321.0 2745.3 2821.4 2868.7 2870.0 2875.6 812.5 835.9 844.5 846.1 847.6 8559.0 8721.6 8741.8 9011.5 9032.1 3296.9 3358.4 3400.9 3453.7 3521.4 7028.5 7238.1 7270.9 7272.3 7276.3 3845.6 4099.9 4153.2 4159.4 4177.1 3418.4 3520.9 4006.6 4011.5 4017.5 2766.5 2992.5 3004.7 3014.1 3045.3 839.8 894.7 898.8 900.9 906.3 709.7 745.1 747.2 752.7 789.5 4452.7 4512.9 4541.5 4547.9 4571.7 775.5 780.8 788.3 805.8 813.1 3144.6 3148.9 3149.5 3149.8 3183.9 8417.8 8527.7 8753.2 8789.9 8811.6 8230.9 8358.9 8383.9 8800.3 8807.3 6456.5 6492.6 6958.5 6958.6 6959.1 4402.9 4562.4 4636.4 4644.0 4683.7 7544.8 8120.7 8189.1 8207.0 8232.5 8243.8 8297.2 8298.8 8335.6 8352.2 5400.1 5413.8 5665.4 5734.2 5740.9 6401.6 6569.5 6696.8 6943.5 7001.2 6176.4 6183.4 6334.9 6364.8 6397.7 5827.4 6091.6 6139.4 6164.9 6193.5 5755.5 5777.7 5782.3 5809.5 5813.4 3757.5 3777.3 3851.7 3932.8 4013.7 3883.1 4111.6 4129.3 4163.6 4180.0
06:18:40 INFO - PROCESS | 3904 |
06:18:40 INFO - PROCESS | 3904 | [#4] prototype.html Cycles:180 Average:4291.19 Median:5006.85 stddev:1343.69 (26.8%) stddev-sans-first:1312.57
06:18:40 INFO - PROCESS | 3904 | Values: 238.4 243.9 247.7 248.3 249.7 4981.1 5081.3 5106.9 5164.7 5172.5 4987.6 5050.8 5093.9 5111.1 5121.2 3581.9 3721.4 3763.8 3767.8 3776.8 3558.9 3661.0 3686.9 3772.0 3779.1 3703.3 3720.6 3725.1 3730.2 3741.3 3639.5 3676.6 3714.1 3723.1 3728.6 3082.3 3140.2 3219.5 3220.2 3232.8 4754.9 4852.6 4929.0 4992.7 5018.3 4108.8 4318.3 4369.9 4375.5 4381.9 5113.2 5247.2 5282.7 5297.4 5328.9 3360.6 3430.6 3578.1 3582.9 3595.5 5425.8 5437.8 5492.6 5540.6 5563.8 3500.2 3541.2 3548.8 3558.8 3566.1 3557.0 3599.9 3607.0 3607.1 3611.3 4047.7 4049.5 4260.5 4280.9 4287.8 5163.3 5183.7 5378.6 5403.7 5416.1 1178.7 1198.1 1205.3 1229.6 1229.7 764.2 767.7 774.4 780.5 797.6 4966.3 5179.1 5186.2 5189.3 5201.2 5379.5 5459.7 5479.5 5491.0 5505.8 5139.6 5204.3 5214.4 5217.9 5223.0 2503.3 2581.5 2602.3 2617.4 2635.6 5151.4 5200.3 5204.8 5214.9 5231.3 4882.8 4950.4 4991.3 5028.3 5041.7 5215.6 5228.4 5236.9 5262.6 5292.9 4679.3 4913.3 4919.8 4928.0 4928.4 4795.5 4822.6 4841.2 4866.2 4889.5 5204.6 5208.7 5210.7 5230.2 5246.9 3284.2 3289.3 3300.5 3300.6 3301.8 4995.4 5023.6 5598.3 5625.6 5626.8 5188.5 5312.4 5315.0 5327.8 5333.9 5137.9 5301.0 5410.4 5421.3 5424.9 5140.2 5242.5 5272.9 5275.1 5285.5 5128.2 5383.2 5407.4 5416.8 5425.8 5214.9 5270.4 5329.0 5331.5 5334.4
06:18:40 INFO - PROCESS | 3904 |
06:18:40 INFO - PROCESS | 3904 | [#5] yui.html Cycles:180 Average:19841.41 Median:4591.25 stddev:27825.54 (606.1%) stddev-sans-first:27866.16
06:18:40 INFO - PROCESS | 3904 | Values: 617.4 674.5 685.4 696.0 709.3 15078.2 15835.8 15910.0 15911.8 16120.4 14194.3 14291.4 14505.2 14522.1 14550.6 506.0 628.3 639.9 640.4 645.5 531.6 627.9 640.5 642.4 650.5 667.0 668.6 673.9 679.0 680.0 525.9 665.6 666.0 666.7 689.8 409.5 474.4 488.2 489.7 502.5 12329.6 13058.2 13062.7 13095.3 13117.4 12738.0 12986.9 13086.6 13236.1 13255.2 12640.2 12944.5 13257.9 13270.3 13688.5 4107.4 4348.7 4394.9 4395.7 4413.5 85008.0 86114.6 87910.6 88127.6 88597.6 9890.4 10146.8 10493.3 10552.7 10691.3 16397.2 19730.6 19734.5 19771.1 19794.2 7368.5 7555.7 7950.2 8013.2 8211.2 4205.5 4327.7 4510.6 4512.5 4527.3 2724.1 2768.4 2813.6 2946.5 2983.4 538.3 539.7 541.6 541.6 548.9 627.5 658.0 686.2 697.9 704.3 9970.8 10018.4 10362.3 10403.8 10413.3 550.3 550.4 580.1 670.0 688.2 4655.2 4765.6 4801.8 4889.0 4901.2 67179.0 69035.0 83541.6 123417.4 126895.0 81507.6 82596.0 84197.0 84901.6 89303.0 71674.0 83331.6 86114.0 87529.0 89615.6 9333.3 9359.9 9679.7 9717.2 9982.0 50057.7 50445.0 50563.2 50798.5 51822.0 67564.7 70144.6 70828.3 70961.0 71201.0 12792.2 12956.9 13002.3 13055.7 13459.7 15452.5 15542.5 15695.7 16211.8 16217.2 14099.4 14140.3 14405.0 14720.5 14989.5 13926.7 14105.4 14798.0 15067.6 15178.2 13089.0 13341.3 13520.7 13540.5 13722.3 11750.6 11955.6 12080.5 12374.3 12478.8 11216.9 12384.3 12419.6 12471.4 12481.0
06:18:40 INFO - PROCESS | 3904 | -------- Summary: end --------
06:18:40 INFO - PROCESS | 3904 |
06:18:41 INFO - TEST-INFO | 3904: exit 0
06:18:41 INFO - TEST-OK | dromaeo_css | took 1118911ms
06:18:41 INFO - TEST-START | kraken
06:18:41 INFO - operating with platform_type : w7_
06:18:41 INFO - Initialising browser for kraken test...
06:18:41 INFO - TEST-INFO | started process 1580 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpgqlqzh\profile http://localhost:49613/getInfo.html)
06:18:46 INFO - PROCESS | 1580 | __metrics Screen width/height:1600/1200
06:18:46 INFO - PROCESS | 1580 | colorDepth:24
06:18:46 INFO - PROCESS | 1580 | Browser inner width/height: 1010/674
06:18:46 INFO - PROCESS | 1580 | __metrics
06:18:47 INFO - PROCESS | 1580 | 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"
06:18:47 INFO - PROCESS | 1580 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
06:18:47 INFO - PROCESS | 1580 | console.error:
06:18:47 INFO - PROCESS | 1580 | Message: Error: Connection closed before committing the transaction.
06:18:47 INFO - PROCESS | 1580 | Stack:
06:18:47 INFO - PROCESS | 1580 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:937:23
06:18:47 INFO - PROCESS | 1580 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7
06:18:47 INFO - PROCESS | 1580 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11
06:18:47 INFO - PROCESS | 1580 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7
06:18:47 INFO - PROCESS | 1580 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7
06:18:47 INFO - PROCESS | 1580 | TaskImpl_run@resource://gre/modules/Task.jsm:324:13
06:18:47 INFO - PROCESS | 1580 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9
06:18:47 INFO - PROCESS | 1580 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15
06:18:47 INFO - PROCESS | 1580 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5
06:18:47 INFO - PROCESS | 1580 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29
06:18:47 INFO - PROCESS | 1580 | Barrier/this.client.addBlocker/promise13.3%
06:19:45 INFO - TinderboxPrint: I/O read bytes / time
206,116,352 / 3,215,870
06:19:45 INFO - TinderboxPrint: I/O write bytes / time
494,476,800 / 4,814,120
06:19:45 INFO - TinderboxPrint: CPU idle
8,251.4 (86.7%)
06:19:45 INFO - TinderboxPrint: CPU user
1,214.9 (12.8%)
06:19:45 INFO - install - Wall time: 6s; CPU: 5.0%; Read bytes: 0; Write bytes: 76129280; Read time: 0; Write time: 1489680
06:19:45 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 206116352; Write bytes: 407534080; Read time: 3215870; Write time: 3257070
06:19:45 INFO - Running post-run listener: _upload_blobber_files
06:19:45 INFO - Blob upload gear active.
06:19:45 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir.
06:19:45 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
06:19:45 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\venv\\Scripts\\blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', 'C:\\slave\\test\\oauth.txt', '-b', 'Fx-Team', '-d', 'C:\\slave\\test\\build\\blobber_upload_dir', '--output-manifest', 'C:\\slave\\test\\build\\uploaded_files.json']
06:19:45 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\venv\Scripts\blobberc.py -u https://blobupload.elasticbeanstalk.com -a C:\slave\test\oauth.txt -b Fx-Team -d C:\slave\test\build\blobber_upload_dir --output-manifest C:\slave\test\build\uploaded_files.json
06:19:46 INFO - (blobuploader) - INFO - Open directory for files ...
06:19:46 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log ...
06:19:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
06:19:46 INFO - (blobuploader) - INFO - Uploading, attempt #1.
06:19:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded
06:19:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
06:19:47 INFO - (blobuploader) - INFO - Done attempting.
06:19:47 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log ...
06:19:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
06:19:47 INFO - (blobuploader) - INFO - Uploading, attempt #1.
06:19:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded
06:19:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
06:19:48 INFO - (blobuploader) - INFO - Done attempting.
06:19:48 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ...
06:19:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
06:19:48 INFO - (blobuploader) - INFO - Uploading, attempt #1.
06:19:56 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
06:19:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
06:19:56 INFO - (blobuploader) - INFO - Done attempting.
06:19:56 INFO - (blobuploader) - INFO - Iteration through files over.
06:19:56 INFO - Return code: 0
06:19:56 INFO - rmtree: C:\slave\test\build\uploaded_files.json
06:19:56 INFO - Using _rmtree_windows ...
06:19:56 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1
06:19:56 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7209af88ad77c4720f0459e914ea9e8055864b97498f158d31b847b71a14f144b07e752e54681ea2cb004884c6c58b3309fa74f07c13b335e82aea87894f7b27", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/419bb7a38f8d85c019a29d7f634778a8b8365d3092b731b4cb3c77ded94e0ae71e7757a2c94e5693c384e957fca8eb066a10b021422076c6ff77ca566832c05d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4bc4c782833882ea12bde3d9496678bf44f889a63ebe64d238e7f2d2dc240eb7a9df32ad84b6c196d7e333002756b7da90c8ab97274c31970fc85ae181e94b07"}
06:19:56 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files
06:19:56 INFO - Writing to file C:\slave\test\properties\blobber_files
06:19:56 INFO - Contents:
06:19:56 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7209af88ad77c4720f0459e914ea9e8055864b97498f158d31b847b71a14f144b07e752e54681ea2cb004884c6c58b3309fa74f07c13b335e82aea87894f7b27", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/419bb7a38f8d85c019a29d7f634778a8b8365d3092b731b4cb3c77ded94e0ae71e7757a2c94e5693c384e957fca8eb066a10b021422076c6ff77ca566832c05d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4bc4c782833882ea12bde3d9496678bf44f889a63ebe64d238e7f2d2dc240eb7a9df32ad84b6c196d7e333002756b7da90c8ab97274c31970fc85ae181e94b07"}
06:19:56 INFO - Running post-run listener: copy_logs_to_upload_dir
06:19:56 INFO - Copying logs to upload dir...
06:19:56 INFO - mkdir: C:\slave\test\build\upload\logs
06:19:56 INFO - Copying logs to upload dir...
06:19:56 INFO - Using _rmtree_windows ...
06:19:56 INFO - Using _rmtree_windows ...
06:19:56 INFO - Using _rmtree_windows ...
06:19:56 INFO - Using _rmtree_windows ...
06:19:56 INFO - Using _rmtree_windows ...
06:19:56 INFO - Using _rmtree_windows ...
06:19:56 INFO - Using _rmtree_windows ...
program finished with exit code 0
elapsedTime=1434.743000
========= master_lag: -0.04 =========
========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 54 secs) (at 2016-09-27 06:19:56.505448) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-27 06:19:56.511766) =========
'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-055
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-055
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-055
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7209af88ad77c4720f0459e914ea9e8055864b97498f158d31b847b71a14f144b07e752e54681ea2cb004884c6c58b3309fa74f07c13b335e82aea87894f7b27", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/419bb7a38f8d85c019a29d7f634778a8b8365d3092b731b4cb3c77ded94e0ae71e7757a2c94e5693c384e957fca8eb066a10b021422076c6ff77ca566832c05d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4bc4c782833882ea12bde3d9496678bf44f889a63ebe64d238e7f2d2dc240eb7a9df32ad84b6c196d7e333002756b7da90c8ab97274c31970fc85ae181e94b07"}
build_url:https://queue.taskcluster.net/v1/task/YPQvFkwPSKusQtWjjH_XKQ/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/YPQvFkwPSKusQtWjjH_XKQ/artifacts/public/build/firefox-52.0a1.en-US.win32.zip'
blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7209af88ad77c4720f0459e914ea9e8055864b97498f158d31b847b71a14f144b07e752e54681ea2cb004884c6c58b3309fa74f07c13b335e82aea87894f7b27", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/419bb7a38f8d85c019a29d7f634778a8b8365d3092b731b4cb3c77ded94e0ae71e7757a2c94e5693c384e957fca8eb066a10b021422076c6ff77ca566832c05d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4bc4c782833882ea12bde3d9496678bf44f889a63ebe64d238e7f2d2dc240eb7a9df32ad84b6c196d7e333002756b7da90c8ab97274c31970fc85ae181e94b07"}'
========= master_lag: 0.01 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-27 06:19:56.626882) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-27 06:19:56.627210) =========
'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-055
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-055
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-055
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
program finished with exit code 0
elapsedTime=0.101000
========= master_lag: 0.01 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-27 06:19:56.740283) =========
========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-09-27 06:19:56.740609) =========
========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-09-27 06:19:57.201546) =========
========= Total master_lag: 0.20 =========