builder: autoland_win7_ix_test-dromaeojs-pgo
slave: t-w732-ix-068
starttime: 1474954401.46
results: success (0)
buildid: 20160926183005
builduid: 6cdfb5f76bfb487da6d7bf0b78afa193
revision: 19e3f7722664812216634b38a72874514e57ee7a
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-26 22:33:21.458701) =========
master: http://buildbot-master110.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-26 22:33:21.459105) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-26 22:33:21.459391) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-26 22:33:21.543577) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-26 22:33:21.543843) =========
'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-068
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-068
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-068
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
--22:33:22-- 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% 9.25 MB/s
22:33:22 (9.25 MB/s) - `archiver_client.py' saved [12141/12141]
program finished with exit code 0
elapsedTime=1.163000
========= master_lag: 0.09 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-26 22:33:22.795614) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 12 secs) (at 2016-09-26 22:33:22.795938) =========
'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-068
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-068
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-068
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=12.801000
========= master_lag: 0.01 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 12 secs) (at 2016-09-26 22:33:35.608613) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-26 22:33:35.609061) =========
'bash' '-c' u'python archiver_client.py mozharness --repo integration/autoland --rev 19e3f7722664812216634b38a72874514e57ee7a --destination scripts --debug'
in dir C:\slave\test\. (timeout 1200 secs)
watching logfiles {}
argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 19e3f7722664812216634b38a72874514e57ee7a --destination scripts --debug']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-068
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-068
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-068
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-26 22:33:35,927 truncating revision to first 12 chars
2016-09-26 22:33:35,927 Setting DEBUG logging.
2016-09-26 22:33:35,927 attempt 1/10
2016-09-26 22:33:35,927 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/19e3f7722664?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2016-09-26 22:33:36,431 unpacking tar archive at: autoland-19e3f7722664/testing/mozharness/
2016-09-26 22:33:36,575 skipping symlink on windows: configs/single_locale/linux32.py
program finished with exit code 0
elapsedTime=1.195000
========= master_lag: 0.03 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-26 22:33:36.833070) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-26 22:33:36.833400) =========
script_repo_revision: 19e3f7722664812216634b38a72874514e57ee7a
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-26 22:33:36.833792) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-26 22:33:36.834058) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-26 22:33:36.844060) =========
========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 31 secs) (at 2016-09-26 22:33:36.844370) =========
'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Autoland' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Autoland'
in dir C:\slave\test\. (timeout 3600 secs) (maxTime 7200 secs)
watching logfiles {}
argv: ['c:/mozilla-build/python27/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-068
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-068
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-068
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
22:33:37 INFO - MultiFileLogger online at 20160926 22:33:37 in C:\slave\test
22:33:37 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland
22:33:37 INFO - Dumping config to C:\slave\test\logs\localconfig.json.
22:33:37 INFO - {'append_to_log': False,
22:33:37 INFO - 'base_work_dir': 'C:\\slave\\test',
22:33:37 INFO - 'blob_upload_branch': 'Autoland',
22:33:37 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt',
22:33:37 INFO - 'branch': 'Autoland',
22:33:37 INFO - 'buildbot_json_path': 'buildprops.json',
22:33:37 INFO - 'config_files': ('talos/windows_config.py',),
22:33:37 INFO - 'default_actions': ('clobber',
22:33:37 INFO - 'read-buildbot-config',
22:33:37 INFO - 'download-and-extract',
22:33:37 INFO - 'populate-webroot',
22:33:37 INFO - 'create-virtualenv',
22:33:37 INFO - 'install',
22:33:37 INFO - 'run-tests'),
22:33:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
22:33:37 INFO - 'download_minidump_stackwalk': True,
22:33:37 INFO - 'download_symbols': 'ondemand',
22:33:37 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python',
22:33:37 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'),
22:33:37 INFO - 'hg': 'c:/mozilla-build/hg/hg',
22:33:37 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python',
22:33:37 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'),
22:33:37 INFO - 'python': 'c:/mozilla-build/python27/python.exe',
22:33:37 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe',
22:33:37 INFO - 'C:/mozilla-build/tooltool.py'),
22:33:37 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe',
22:33:37 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')},
22:33:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
22:33:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
22:33:37 INFO - 'installer_path': 'installer.exe',
22:33:37 INFO - 'log_level': 'info',
22:33:37 INFO - 'log_name': 'talos',
22:33:37 INFO - 'log_to_console': True,
22:33:37 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe',
22:33:37 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe',
22:33:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest',
22:33:37 INFO - 'opt_config_files': (),
22:33:37 INFO - 'pip_index': False,
22:33:37 INFO - 'sps_profile': False,
22:33:37 INFO - 'sps_profile_interval': 0,
22:33:37 INFO - 'suite': 'dromaeojs',
22:33:37 INFO - 'system_bits': '32',
22:33:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
22:33:37 INFO - 'title': 'T-W732-IX-068',
22:33:37 INFO - 'use_talos_json': True,
22:33:37 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'),
22:33:37 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv',
22:33:37 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll',
22:33:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
22:33:37 INFO - 'work_dir': 'build'}
22:33:37 INFO - [mozharness: 2016-09-27 05:33:37.584000Z] Running clobber step.
22:33:37 INFO - Running pre-action listener: _resource_record_pre_action
22:33:37 INFO - Running main action method: clobber
22:33:37 INFO - rmtree: C:\slave\test\build
22:33:37 INFO - Using _rmtree_windows ...
22:33:37 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1
22:35:17 INFO - Running post-action listener: _resource_record_post_action
22:35:17 INFO - [mozharness: 2016-09-27 05:35:17.144000Z] Finished clobber step (success)
22:35:17 INFO - [mozharness: 2016-09-27 05:35:17.144000Z] Running read-buildbot-config step.
22:35:17 INFO - Running pre-action listener: _resource_record_pre_action
22:35:17 INFO - Running main action method: read_buildbot_config
22:35:17 INFO - Using buildbot properties:
22:35:17 INFO - {
22:35:17 INFO - "project": "",
22:35:17 INFO - "product": "firefox",
22:35:17 INFO - "script_repo_revision": "production",
22:35:17 INFO - "builddir": "autoland_win7_ix_test-dromaeojs",
22:35:17 INFO - "repository": "",
22:35:17 INFO - "buildername": "Windows 7 32-bit autoland pgo talos dromaeojs",
22:35:17 INFO - "buildid": "20160926183005",
22:35:17 INFO - "pgo_build": "True",
22:35:17 INFO - "basedir": "C:\\slave\\test",
22:35:17 INFO - "buildnumber": 97,
22:35:17 INFO - "slavename": "t-w732-ix-068",
22:35:17 INFO - "master": "http://buildbot-master110.bb.releng.scl3.mozilla.com:8201/",
22:35:17 INFO - "platform": "win7_ix",
22:35:17 INFO - "scheduler": "tests-autoland-win32-pgo-talos",
22:35:17 INFO - "branch": "autoland",
22:35:17 INFO - "repo_path": "integration/autoland",
22:35:17 INFO - "revision": "19e3f7722664812216634b38a72874514e57ee7a",
22:35:17 INFO - "stage_platform": "win32-pgo",
22:35:17 INFO - "builduid": "6cdfb5f76bfb487da6d7bf0b78afa193",
22:35:17 INFO - "slavebuilddir": "test"
22:35:17 INFO - }
22:35:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/M6BxDJNQRVaKb9MGEgyr_A/artifacts/public/build/firefox-52.0a1.en-US.win32.zip.
22:35:17 INFO - Running post-action listener: _resource_record_post_action
22:35:17 INFO - [mozharness: 2016-09-27 05:35:17.148000Z] Finished read-buildbot-config step (success)
22:35:17 INFO - [mozharness: 2016-09-27 05:35:17.148000Z] Running download-and-extract step.
22:35:17 INFO - Running pre-action listener: _resource_record_pre_action
22:35:17 INFO - Running main action method: download_and_extract
22:35:17 INFO - mkdir: C:\slave\test\build\tests
22:35:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
22:35:17 INFO - trying https://queue.taskcluster.net/v1/task/M6BxDJNQRVaKb9MGEgyr_A/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json
22:35:17 INFO - Downloading https://queue.taskcluster.net/v1/task/M6BxDJNQRVaKb9MGEgyr_A/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json to C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json
22:35:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/M6BxDJNQRVaKb9MGEgyr_A/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json', 'file_name': 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.test_packages.json'}, attempt #1
22:35:18 INFO - Downloaded 1218 bytes.
22:35:18 INFO - Reading from file C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json
22:35:18 INFO - Using the following test package requirements:
22:35:18 INFO - {u'common': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
22:35:18 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
22:35:18 INFO - u'firefox-52.0a1.en-US.win32.cppunittest.tests.zip'],
22:35:18 INFO - u'gtest': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
22:35:18 INFO - u'jittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
22:35:18 INFO - u'jsshell-win32.zip'],
22:35:18 INFO - u'mochitest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
22:35:18 INFO - u'firefox-52.0a1.en-US.win32.mochitest.tests.zip'],
22:35:18 INFO - u'mozbase': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
22:35:18 INFO - u'reftest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
22:35:18 INFO - u'firefox-52.0a1.en-US.win32.reftest.tests.zip'],
22:35:18 INFO - u'talos': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
22:35:18 INFO - u'firefox-52.0a1.en-US.win32.talos.tests.zip'],
22:35:18 INFO - u'web-platform': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
22:35:18 INFO - u'firefox-52.0a1.en-US.win32.web-platform.tests.zip'],
22:35:18 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
22:35:18 INFO - u'firefox-52.0a1.en-US.win32.xpcshell.tests.zip']}
22:35:18 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip'] for test suite category: common
22:35:18 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/M6BxDJNQRVaKb9MGEgyr_A/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip
22:35:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/M6BxDJNQRVaKb9MGEgyr_A/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1
22:35:18 INFO - Fetch https://queue.taskcluster.net/v1/task/M6BxDJNQRVaKb9MGEgyr_A/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory
22:35:18 INFO - Expected file size: 17321496
22:35:48 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
22:35:48 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/M6BxDJNQRVaKb9MGEgyr_A/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip
22:35:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/M6BxDJNQRVaKb9MGEgyr_A/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1
22:35:48 INFO - Fetch https://queue.taskcluster.net/v1/task/M6BxDJNQRVaKb9MGEgyr_A/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory
22:35:48 INFO - Expected file size: 17321496
22:35:59 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/M6BxDJNQRVaKb9MGEgyr_A/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip
22:35:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/M6BxDJNQRVaKb9MGEgyr_A/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip'}, attempt #1
22:35:59 INFO - Fetch https://queue.taskcluster.net/v1/task/M6BxDJNQRVaKb9MGEgyr_A/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip into memory
22:35:59 INFO - Expected file size: 14414057
22:36:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
22:36:13 INFO - trying https://queue.taskcluster.net/v1/task/M6BxDJNQRVaKb9MGEgyr_A/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
22:36:13 INFO - Downloading https://queue.taskcluster.net/v1/task/M6BxDJNQRVaKb9MGEgyr_A/artifacts/public/build/firefox-52.0a1.en-US.win32.zip to C:\slave\test\build\firefox-52.0a1.en-US.win32.zip
22:36:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/M6BxDJNQRVaKb9MGEgyr_A/artifacts/public/build/firefox-52.0a1.en-US.win32.zip', 'file_name': 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.zip'}, attempt #1
22:36:17 INFO - Downloaded 54740690 bytes.
22:36:17 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/M6BxDJNQRVaKb9MGEgyr_A/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
22:36:17 INFO - mkdir: C:\slave\test\properties
22:36:17 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url
22:36:17 INFO - Writing to file C:\slave\test\properties\build_url
22:36:17 INFO - Contents:
22:36:17 INFO - build_url:https://queue.taskcluster.net/v1/task/M6BxDJNQRVaKb9MGEgyr_A/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
22:36:17 INFO - Running post-action listener: _resource_record_post_action
22:36:17 INFO - Running post-action listener: set_extra_try_arguments
22:36:17 INFO - [mozharness: 2016-09-27 05:36:17.707000Z] Finished download-and-extract step (success)
22:36:17 INFO - [mozharness: 2016-09-27 05:36:17.707000Z] Running populate-webroot step.
22:36:17 INFO - Running pre-action listener: _resource_record_pre_action
22:36:17 INFO - Running main action method: populate_webroot
22:36:17 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']},
22:36:17 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']},
22:36:17 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome',
22:36:17 INFO - u'--tppagecycles',
22:36:17 INFO - u'7'],
22:36:17 INFO - u'tests': [u'tsvgm']}},
22:36:17 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'],
22:36:17 INFO - u'tests': [u'tresize', u'tcanvasmark']},
22:36:17 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']},
22:36:17 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'],
22:36:17 INFO - u'tests': [u'dromaeo_css', u'kraken']},
22:36:17 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']},
22:36:17 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
22:36:17 INFO - u'talos_options': [u'--disable-e10s'],
22:36:17 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
22:36:17 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
22:36:17 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
22:36:17 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
22:36:17 INFO - u'talos_options': [u'--disable-e10s'],
22:36:17 INFO - u'tests': [u'damp', u'tps']},
22:36:17 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
22:36:17 INFO - u'tests': [u'damp', u'tps']},
22:36:17 INFO - u'g3': {u'talos_options': [u'--disable-e10s'],
22:36:17 INFO - u'tests': [u'dromaeo_dom']},
22:36:17 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']},
22:36:17 INFO - u'g4': {u'talos_options': [u'--disable-e10s'],
22:36:17 INFO - u'tests': [u'basic_compositor_video']},
22:36:17 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']},
22:36:17 INFO - u'other': {u'talos_options': [u'--disable-e10s'],
22:36:17 INFO - u'tests': [u'a11yr',
22:36:17 INFO - u'ts_paint',
22:36:17 INFO - u'tpaint',
22:36:17 INFO - u'sessionrestore',
22:36:17 INFO - u'sessionrestore_no_auto_restore',
22:36:17 INFO - u'tabpaint']},
22:36:17 INFO - u'other-e10s': {u'tests': [u'a11yr',
22:36:17 INFO - u'ts_paint',
22:36:17 INFO - u'tpaint',
22:36:17 INFO - u'sessionrestore',
22:36:17 INFO - u'sessionrestore_no_auto_restore',
22:36:17 INFO - u'tabpaint']},
22:36:17 INFO - u'other-e10s_l64': {u'tests': [u'a11yr',
22:36:17 INFO - u'ts_paint',
22:36:17 INFO - u'tpaint',
22:36:17 INFO - u'sessionrestore',
22:36:17 INFO - u'sessionrestore_no_auto_restore',
22:36:17 INFO - u'tabpaint']},
22:36:17 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr',
22:36:17 INFO - u'ts_paint',
22:36:17 INFO - u'tpaint',
22:36:17 INFO - u'sessionrestore',
22:36:17 INFO - u'sessionrestore_no_auto_restore',
22:36:17 INFO - u'tabpaint']},
22:36:17 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'],
22:36:17 INFO - u'tests': [u'a11yr',
22:36:17 INFO - u'ts_paint',
22:36:17 INFO - u'tpaint',
22:36:17 INFO - u'sessionrestore',
22:36:17 INFO - u'sessionrestore_no_auto_restore',
22:36:17 INFO - u'tabpaint']},
22:36:17 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'],
22:36:17 INFO - u'tests': [u'a11yr',
22:36:17 INFO - u'ts_paint',
22:36:17 INFO - u'tpaint',
22:36:17 INFO - u'sessionrestore',
22:36:17 INFO - u'sessionrestore_no_auto_restore',
22:36:17 INFO - u'tabpaint']},
22:36:17 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'],
22:36:17 INFO - u'tests': [u'tsvgx',
22:36:17 INFO - u'tsvgr_opacity',
22:36:17 INFO - u'tart',
22:36:17 INFO - u'tscrollx',
22:36:17 INFO - u'cart']},
22:36:17 INFO - u'svgr-e10s': {u'tests': [u'tsvgx',
22:36:17 INFO - u'tsvgr_opacity',
22:36:17 INFO - u'tart',
22:36:17 INFO - u'tscrollx',
22:36:17 INFO - u'cart']},
22:36:17 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
22:36:17 INFO - u'talos_options': [u'--disable-e10s'],
22:36:17 INFO - u'tests': [u'tp5o']},
22:36:17 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
22:36:17 INFO - u'tests': [u'tp5o']},
22:36:17 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
22:36:17 INFO - u'talos_options': [u'--disable-e10s',
22:36:17 INFO - u'--xperf_path',
22:36:17 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
22:36:17 INFO - u'tests': [u'tp5n']},
22:36:17 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
22:36:17 INFO - u'talos_options': [u'--xperf_path',
22:36:17 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
22:36:17 INFO - u'tests': [u'tp5n']}},
22:36:17 INFO - u'talos.zip': {u'path': u'',
22:36:17 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}}
22:36:17 INFO - Running post-action listener: _resource_record_post_action
22:36:17 INFO - [mozharness: 2016-09-27 05:36:17.805000Z] Finished populate-webroot step (success)
22:36:17 INFO - [mozharness: 2016-09-27 05:36:17.805000Z] Running create-virtualenv step.
22:36:17 INFO - Running pre-action listener: _resource_record_pre_action
22:36:17 INFO - Running main action method: create_virtualenv
22:36:17 INFO - Creating virtualenv C:\slave\test\build/venv
22:36:17 INFO - mkdir: C:\slave\test\build\venv\Scripts
22:36:17 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll
22:36:17 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'!
22:36:17 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
22:36:17 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv
22:36:25 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe
22:36:28 INFO - Installing distribute......................................................................................................................................................................................done.
22:36:30 INFO - Return code: 0
22:36:30 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version']
22:36:30 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version
22:36:30 INFO - Reading from file tmpfile_stdout
22:36:30 INFO - Output received:
22:36:30 INFO - pip 0.8.2 from c:\slave\test\build\venv\lib\site-packages\pip-0.8.2-py2.7.egg (python 2.7)
22:36:30 INFO - Using _rmtree_windows ...
22:36:30 INFO - Using _rmtree_windows ...
22:36:30 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv
22:36: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')]}
22:36:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
22:36: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')]}
22:36:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
22:36: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01801640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018A9E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017AB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C8E90>, '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 0x0121A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0197E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-068', 'USERDOMAIN': 'T-W732-IX-068', '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-068', '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
22:36: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', 'pip>=1.5'] in C:\slave\test\build
22:36: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 pip>=1.5
22:36:30 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
22:36:30 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
22:36:30 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
22:36:30 INFO - 'COMPUTERNAME': 'T-W732-IX-068',
22:36:30 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
22:36:30 INFO - 'CYGWINBASE': 'C:\\cygwin',
22:36:30 INFO - 'DCLOCATION': 'SCL3',
22:36:30 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
22:36:30 INFO - 'FP_NO_HOST_CHECK': 'NO',
22:36:30 INFO - 'HOMEDRIVE': 'C:',
22:36:30 INFO - 'HOMEPATH': '\\Users\\cltbld',
22:36:30 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
22:36:30 INFO - 'KTS_VERSION': '1.19c',
22:36:30 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
22:36:30 INFO - 'LOGONSERVER': '\\\\T-W732-IX-068',
22:36:30 INFO - 'MONDIR': 'C:\\Monitor_config\\',
22:36:30 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
22:36:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
22:36:30 INFO - 'MOZ_NO_REMOTE': '1',
22:36:30 INFO - 'NO_EM_RESTART': '1',
22:36:30 INFO - 'NUMBER_OF_PROCESSORS': '8',
22:36:30 INFO - 'OS': 'Windows_NT',
22:36:30 INFO - 'OURDRIVE': 'C:',
22:36: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;',
22:36:30 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
22:36:30 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
22:36:30 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
22:36:30 INFO - 'PROCESSOR_LEVEL': '6',
22:36:30 INFO - 'PROCESSOR_REVISION': '1e05',
22:36:30 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
22:36:30 INFO - 'PROGRAMFILES': 'C:\\Program Files',
22:36:30 INFO - 'PROMPT': '$P$G',
22:36:30 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
22:36:30 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
22:36:30 INFO - 'PUBLIC': 'C:\\Users\\Public',
22:36:30 INFO - 'PWD': 'C:\\slave\\test',
22:36:30 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
22:36:30 INFO - 'SLAVEDIR': 'C:\\slave\\',
22:36:30 INFO - 'SYSTEMDRIVE': 'C:',
22:36:30 INFO - 'SYSTEMROOT': 'C:\\windows',
22:36:30 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
22:36:30 INFO - 'TEST1': 'testie',
22:36:30 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
22:36:30 INFO - 'USERDOMAIN': 'T-W732-IX-068',
22:36:30 INFO - 'USERNAME': 'cltbld',
22:36:30 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
22:36:30 INFO - 'WINDIR': 'C:\\windows',
22:36:30 INFO - 'WINDOWS_TRACING_FLAGS': '3',
22:36:30 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
22:36:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
22:36:31 INFO - Ignoring indexes: http://pypi.python.org/simple/
22:36:31 INFO - Downloading/unpacking pip>=1.5
22:36:35 INFO - Running setup.py egg_info for package pip
22:36:35 INFO - warning: no files found matching 'pip\cacert.pem'
22:36:35 INFO - warning: no files found matching '*.html' under directory 'docs'
22:36:35 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
22:36:35 INFO - no previously-included directories found matching 'docs\_build\_sources'
22:36:35 INFO - Installing collected packages: pip
22:36:35 INFO - Found existing installation: pip 0.8.2
22:36:35 INFO - Uninstalling pip:
22:36:35 INFO - Successfully uninstalled pip
22:36:35 INFO - Running setup.py install for pip
22:36:35 INFO - warning: no files found matching 'pip\cacert.pem'
22:36:35 INFO - warning: no files found matching '*.html' under directory 'docs'
22:36:35 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
22:36:35 INFO - no previously-included directories found matching 'docs\_build\_sources'
22:36:35 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts
22:36:35 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts
22:36:35 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts
22:36:35 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts
22:36:35 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts
22:36:35 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts
22:36:35 INFO - Successfully installed pip
22:36:35 INFO - Cleaning up...
22:36:35 INFO - Return code: 0
22:36:35 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv
22:36: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')]}
22:36:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
22:36: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')]}
22:36:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
22:36:35 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 0x01801640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018A9E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017AB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C8E90>, '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 0x0121A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0197E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-068', 'USERDOMAIN': 'T-W732-IX-068', '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-068', '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
22:36:35 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
22:36:35 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
22:36:35 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
22:36:35 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
22:36:35 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
22:36:35 INFO - 'COMPUTERNAME': 'T-W732-IX-068',
22:36:35 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
22:36:35 INFO - 'CYGWINBASE': 'C:\\cygwin',
22:36:35 INFO - 'DCLOCATION': 'SCL3',
22:36:35 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
22:36:35 INFO - 'FP_NO_HOST_CHECK': 'NO',
22:36:35 INFO - 'HOMEDRIVE': 'C:',
22:36:35 INFO - 'HOMEPATH': '\\Users\\cltbld',
22:36:35 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
22:36:35 INFO - 'KTS_VERSION': '1.19c',
22:36:35 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
22:36:35 INFO - 'LOGONSERVER': '\\\\T-W732-IX-068',
22:36:35 INFO - 'MONDIR': 'C:\\Monitor_config\\',
22:36:35 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
22:36:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
22:36:35 INFO - 'MOZ_NO_REMOTE': '1',
22:36:35 INFO - 'NO_EM_RESTART': '1',
22:36:35 INFO - 'NUMBER_OF_PROCESSORS': '8',
22:36:35 INFO - 'OS': 'Windows_NT',
22:36:35 INFO - 'OURDRIVE': 'C:',
22:36: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;',
22:36:35 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
22:36:35 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
22:36:35 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
22:36:35 INFO - 'PROCESSOR_LEVEL': '6',
22:36:35 INFO - 'PROCESSOR_REVISION': '1e05',
22:36:35 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
22:36:35 INFO - 'PROGRAMFILES': 'C:\\Program Files',
22:36:35 INFO - 'PROMPT': '$P$G',
22:36:35 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
22:36:35 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
22:36:35 INFO - 'PUBLIC': 'C:\\Users\\Public',
22:36:35 INFO - 'PWD': 'C:\\slave\\test',
22:36:35 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
22:36:35 INFO - 'SLAVEDIR': 'C:\\slave\\',
22:36:35 INFO - 'SYSTEMDRIVE': 'C:',
22:36:35 INFO - 'SYSTEMROOT': 'C:\\windows',
22:36:35 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
22:36:35 INFO - 'TEST1': 'testie',
22:36:35 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
22:36:35 INFO - 'USERDOMAIN': 'T-W732-IX-068',
22:36:35 INFO - 'USERNAME': 'cltbld',
22:36:35 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
22:36:35 INFO - 'WINDIR': 'C:\\windows',
22:36:35 INFO - 'WINDOWS_TRACING_FLAGS': '3',
22:36:35 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
22:36:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
22:36:37 INFO - Ignoring indexes: https://pypi.python.org/simple/
22:36:38 INFO - Downloading/unpacking psutil>=3.1.1
22:36:38 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
22:36:38 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
22:36:39 INFO - Installing collected packages: psutil
22:36:39 INFO - Successfully installed psutil
22:36:39 INFO - Cleaning up...
22:36:39 INFO - Return code: 0
22:36:39 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv
22:36:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
22:36:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
22:36:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
22:36:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
22:36:39 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01801640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018A9E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017AB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C8E90>, '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 0x0121A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0197E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-068', 'USERDOMAIN': 'T-W732-IX-068', '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-068', '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
22:36:39 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in C:\slave\test\build
22:36:39 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3
22:36:39 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
22:36:39 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
22:36:39 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
22:36:39 INFO - 'COMPUTERNAME': 'T-W732-IX-068',
22:36:39 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
22:36:39 INFO - 'CYGWINBASE': 'C:\\cygwin',
22:36:39 INFO - 'DCLOCATION': 'SCL3',
22:36:39 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
22:36:39 INFO - 'FP_NO_HOST_CHECK': 'NO',
22:36:39 INFO - 'HOMEDRIVE': 'C:',
22:36:39 INFO - 'HOMEPATH': '\\Users\\cltbld',
22:36:39 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
22:36:39 INFO - 'KTS_VERSION': '1.19c',
22:36:39 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
22:36:39 INFO - 'LOGONSERVER': '\\\\T-W732-IX-068',
22:36:39 INFO - 'MONDIR': 'C:\\Monitor_config\\',
22:36:39 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
22:36:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
22:36:39 INFO - 'MOZ_NO_REMOTE': '1',
22:36:39 INFO - 'NO_EM_RESTART': '1',
22:36:39 INFO - 'NUMBER_OF_PROCESSORS': '8',
22:36:39 INFO - 'OS': 'Windows_NT',
22:36:39 INFO - 'OURDRIVE': 'C:',
22:36:39 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
22:36:39 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
22:36:39 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
22:36:39 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
22:36:39 INFO - 'PROCESSOR_LEVEL': '6',
22:36:39 INFO - 'PROCESSOR_REVISION': '1e05',
22:36:39 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
22:36:39 INFO - 'PROGRAMFILES': 'C:\\Program Files',
22:36:39 INFO - 'PROMPT': '$P$G',
22:36:39 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
22:36:39 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
22:36:39 INFO - 'PUBLIC': 'C:\\Users\\Public',
22:36:39 INFO - 'PWD': 'C:\\slave\\test',
22:36:39 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
22:36:39 INFO - 'SLAVEDIR': 'C:\\slave\\',
22:36:39 INFO - 'SYSTEMDRIVE': 'C:',
22:36:39 INFO - 'SYSTEMROOT': 'C:\\windows',
22:36:39 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
22:36:39 INFO - 'TEST1': 'testie',
22:36:39 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
22:36:39 INFO - 'USERDOMAIN': 'T-W732-IX-068',
22:36:39 INFO - 'USERNAME': 'cltbld',
22:36:39 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
22:36:39 INFO - 'WINDIR': 'C:\\windows',
22:36:39 INFO - 'WINDOWS_TRACING_FLAGS': '3',
22:36:39 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
22:36:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
22:36:39 INFO - Ignoring indexes: https://pypi.python.org/simple/
22:36:39 INFO - Downloading/unpacking mozsystemmonitor==0.3
22:36:39 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
22:36:39 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
22:36:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz
22:36:40 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor
22:36:40 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)
22:36:40 INFO - Installing collected packages: mozsystemmonitor
22:36:40 INFO - Running setup.py install for mozsystemmonitor
22:36:40 INFO - Successfully installed mozsystemmonitor
22:36:40 INFO - Cleaning up...
22:36:41 INFO - Return code: 0
22:36:41 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv
22:36:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
22:36:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
22:36:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
22:36:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
22:36:41 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x01801640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018A9E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017AB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C8E90>, '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 0x0121A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0197E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-068', 'USERDOMAIN': 'T-W732-IX-068', '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-068', '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
22:36:41 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in C:\slave\test\build
22:36:41 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1
22:36:41 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
22:36:41 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
22:36:41 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
22:36:41 INFO - 'COMPUTERNAME': 'T-W732-IX-068',
22:36:41 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
22:36:41 INFO - 'CYGWINBASE': 'C:\\cygwin',
22:36:41 INFO - 'DCLOCATION': 'SCL3',
22:36:41 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
22:36:41 INFO - 'FP_NO_HOST_CHECK': 'NO',
22:36:41 INFO - 'HOMEDRIVE': 'C:',
22:36:41 INFO - 'HOMEPATH': '\\Users\\cltbld',
22:36:41 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
22:36:41 INFO - 'KTS_VERSION': '1.19c',
22:36:41 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
22:36:41 INFO - 'LOGONSERVER': '\\\\T-W732-IX-068',
22:36:41 INFO - 'MONDIR': 'C:\\Monitor_config\\',
22:36:41 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
22:36:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
22:36:41 INFO - 'MOZ_NO_REMOTE': '1',
22:36:41 INFO - 'NO_EM_RESTART': '1',
22:36:41 INFO - 'NUMBER_OF_PROCESSORS': '8',
22:36:41 INFO - 'OS': 'Windows_NT',
22:36:41 INFO - 'OURDRIVE': 'C:',
22:36:41 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
22:36:41 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
22:36:41 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
22:36:41 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
22:36:41 INFO - 'PROCESSOR_LEVEL': '6',
22:36:41 INFO - 'PROCESSOR_REVISION': '1e05',
22:36:41 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
22:36:41 INFO - 'PROGRAMFILES': 'C:\\Program Files',
22:36:41 INFO - 'PROMPT': '$P$G',
22:36:41 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
22:36:41 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
22:36:41 INFO - 'PUBLIC': 'C:\\Users\\Public',
22:36:41 INFO - 'PWD': 'C:\\slave\\test',
22:36:41 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
22:36:41 INFO - 'SLAVEDIR': 'C:\\slave\\',
22:36:41 INFO - 'SYSTEMDRIVE': 'C:',
22:36:41 INFO - 'SYSTEMROOT': 'C:\\windows',
22:36:41 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
22:36:41 INFO - 'TEST1': 'testie',
22:36:41 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
22:36:41 INFO - 'USERDOMAIN': 'T-W732-IX-068',
22:36:41 INFO - 'USERNAME': 'cltbld',
22:36:41 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
22:36:41 INFO - 'WINDIR': 'C:\\windows',
22:36:41 INFO - 'WINDOWS_TRACING_FLAGS': '3',
22:36:41 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
22:36:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
22:36:41 INFO - Ignoring indexes: https://pypi.python.org/simple/
22:36:41 INFO - Downloading/unpacking jsonschema==2.5.1
22:36:41 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
22:36:41 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
22:36:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl
22:36:42 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1)
22:36:42 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
22:36:42 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
22:36:43 INFO - Downloading functools32-3.2.3-2.tar.gz
22:36:43 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32
22:36:43 INFO - warning: no files found matching '*.txt'
22:36:43 INFO - no previously-included directories found matching 'build'
22:36:43 INFO - no previously-included directories found matching 'dist'
22:36:43 INFO - no previously-included directories found matching '.git*'
22:36:43 INFO - Installing collected packages: jsonschema, functools32
22:36:43 INFO - Running setup.py install for functools32
22:36:43 INFO - warning: no files found matching '*.txt'
22:36:43 INFO - no previously-included directories found matching 'build'
22:36:43 INFO - no previously-included directories found matching 'dist'
22:36:43 INFO - no previously-included directories found matching '.git*'
22:36:43 INFO - Successfully installed jsonschema functools32
22:36:43 INFO - Cleaning up...
22:36:43 INFO - Return code: 0
22:36:43 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv
22:36:43 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')]}
22:36:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
22:36:43 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')]}
22:36:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
22:36:43 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 0x01801640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018A9E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017AB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C8E90>, '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 0x0121A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0197E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-068', 'USERDOMAIN': 'T-W732-IX-068', '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-068', '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
22:36:43 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
22:36:43 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
22:36:43 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
22:36:43 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
22:36:43 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
22:36:43 INFO - 'COMPUTERNAME': 'T-W732-IX-068',
22:36:43 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
22:36:43 INFO - 'CYGWINBASE': 'C:\\cygwin',
22:36:43 INFO - 'DCLOCATION': 'SCL3',
22:36:43 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
22:36:43 INFO - 'FP_NO_HOST_CHECK': 'NO',
22:36:43 INFO - 'HOMEDRIVE': 'C:',
22:36:43 INFO - 'HOMEPATH': '\\Users\\cltbld',
22:36:43 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
22:36:43 INFO - 'KTS_VERSION': '1.19c',
22:36:43 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
22:36:43 INFO - 'LOGONSERVER': '\\\\T-W732-IX-068',
22:36:43 INFO - 'MONDIR': 'C:\\Monitor_config\\',
22:36:43 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
22:36:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
22:36:43 INFO - 'MOZ_NO_REMOTE': '1',
22:36:43 INFO - 'NO_EM_RESTART': '1',
22:36:43 INFO - 'NUMBER_OF_PROCESSORS': '8',
22:36:43 INFO - 'OS': 'Windows_NT',
22:36:43 INFO - 'OURDRIVE': 'C:',
22:36:43 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;',
22:36:43 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
22:36:43 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
22:36:43 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
22:36:43 INFO - 'PROCESSOR_LEVEL': '6',
22:36:43 INFO - 'PROCESSOR_REVISION': '1e05',
22:36:43 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
22:36:43 INFO - 'PROGRAMFILES': 'C:\\Program Files',
22:36:43 INFO - 'PROMPT': '$P$G',
22:36:43 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
22:36:43 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
22:36:43 INFO - 'PUBLIC': 'C:\\Users\\Public',
22:36:43 INFO - 'PWD': 'C:\\slave\\test',
22:36:43 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
22:36:43 INFO - 'SLAVEDIR': 'C:\\slave\\',
22:36:43 INFO - 'SYSTEMDRIVE': 'C:',
22:36:43 INFO - 'SYSTEMROOT': 'C:\\windows',
22:36:43 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
22:36:43 INFO - 'TEST1': 'testie',
22:36:43 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
22:36:43 INFO - 'USERDOMAIN': 'T-W732-IX-068',
22:36:43 INFO - 'USERNAME': 'cltbld',
22:36:43 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
22:36:43 INFO - 'WINDIR': 'C:\\windows',
22:36:43 INFO - 'WINDOWS_TRACING_FLAGS': '3',
22:36:44 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
22:36:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
22:36:44 INFO - Ignoring indexes: https://pypi.python.org/simple/
22:36:44 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages
22:36:44 INFO - Cleaning up...
22:36:44 INFO - Return code: 0
22:36:44 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv
22:36:44 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')]}
22:36:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
22:36:44 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')]}
22:36:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
22:36:44 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 0x01801640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018A9E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017AB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C8E90>, '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 0x0121A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0197E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-068', 'USERDOMAIN': 'T-W732-IX-068', '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-068', '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
22:36:44 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
22:36:44 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
22:36:44 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
22:36:44 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
22:36:44 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
22:36:44 INFO - 'COMPUTERNAME': 'T-W732-IX-068',
22:36:44 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
22:36:44 INFO - 'CYGWINBASE': 'C:\\cygwin',
22:36:44 INFO - 'DCLOCATION': 'SCL3',
22:36:44 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
22:36:44 INFO - 'FP_NO_HOST_CHECK': 'NO',
22:36:44 INFO - 'HOMEDRIVE': 'C:',
22:36:44 INFO - 'HOMEPATH': '\\Users\\cltbld',
22:36:44 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
22:36:44 INFO - 'KTS_VERSION': '1.19c',
22:36:44 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
22:36:44 INFO - 'LOGONSERVER': '\\\\T-W732-IX-068',
22:36:44 INFO - 'MONDIR': 'C:\\Monitor_config\\',
22:36:44 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
22:36:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
22:36:44 INFO - 'MOZ_NO_REMOTE': '1',
22:36:44 INFO - 'NO_EM_RESTART': '1',
22:36:44 INFO - 'NUMBER_OF_PROCESSORS': '8',
22:36:44 INFO - 'OS': 'Windows_NT',
22:36:44 INFO - 'OURDRIVE': 'C:',
22:36:44 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;',
22:36:44 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
22:36:44 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
22:36:44 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
22:36:44 INFO - 'PROCESSOR_LEVEL': '6',
22:36:44 INFO - 'PROCESSOR_REVISION': '1e05',
22:36:44 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
22:36:44 INFO - 'PROGRAMFILES': 'C:\\Program Files',
22:36:44 INFO - 'PROMPT': '$P$G',
22:36:44 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
22:36:44 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
22:36:44 INFO - 'PUBLIC': 'C:\\Users\\Public',
22:36:44 INFO - 'PWD': 'C:\\slave\\test',
22:36:44 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
22:36:44 INFO - 'SLAVEDIR': 'C:\\slave\\',
22:36:44 INFO - 'SYSTEMDRIVE': 'C:',
22:36:44 INFO - 'SYSTEMROOT': 'C:\\windows',
22:36:44 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
22:36:44 INFO - 'TEST1': 'testie',
22:36:44 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
22:36:44 INFO - 'USERDOMAIN': 'T-W732-IX-068',
22:36:44 INFO - 'USERNAME': 'cltbld',
22:36:44 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
22:36:44 INFO - 'WINDIR': 'C:\\windows',
22:36:44 INFO - 'WINDOWS_TRACING_FLAGS': '3',
22:36:44 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
22:36:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
22:36:44 INFO - Ignoring indexes: https://pypi.python.org/simple/
22:36:44 INFO - Downloading/unpacking blobuploader==1.2.4
22:36:44 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
22:36:44 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
22:36:45 INFO - Downloading blobuploader-1.2.4.tar.gz
22:36:45 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader
22:36:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4)
22:36:45 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
22:36:45 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
22:36:45 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests
22:36:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4)
22:36:46 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
22:36:46 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
22:36:46 INFO - Downloading docopt-0.6.1.tar.gz
22:36:46 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt
22:36:46 INFO - Installing collected packages: blobuploader, requests, docopt
22:36:46 INFO - Running setup.py install for blobuploader
22:36:46 INFO - Running setup.py install for requests
22:36:47 INFO - Running setup.py install for docopt
22:36:47 INFO - Successfully installed blobuploader requests docopt
22:36:47 INFO - Cleaning up...
22:36:48 INFO - Return code: 0
22:36:48 INFO - Installing None into virtualenv C:\slave\test\build/venv
22:36:48 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')]}
22:36:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
22:36:48 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')]}
22:36:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
22:36:48 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 0x01801640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018A9E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017AB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C8E90>, '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 0x0121A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0197E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-068', 'USERDOMAIN': 'T-W732-IX-068', '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-068', '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
22:36:49 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
22:36:49 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
22:36:49 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
22:36:49 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
22:36:49 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
22:36:49 INFO - 'COMPUTERNAME': 'T-W732-IX-068',
22:36:49 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
22:36:49 INFO - 'CYGWINBASE': 'C:\\cygwin',
22:36:49 INFO - 'DCLOCATION': 'SCL3',
22:36:49 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
22:36:49 INFO - 'FP_NO_HOST_CHECK': 'NO',
22:36:49 INFO - 'HOMEDRIVE': 'C:',
22:36:49 INFO - 'HOMEPATH': '\\Users\\cltbld',
22:36:49 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
22:36:49 INFO - 'KTS_VERSION': '1.19c',
22:36:49 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
22:36:49 INFO - 'LOGONSERVER': '\\\\T-W732-IX-068',
22:36:49 INFO - 'MONDIR': 'C:\\Monitor_config\\',
22:36:49 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
22:36:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
22:36:49 INFO - 'MOZ_NO_REMOTE': '1',
22:36:49 INFO - 'NO_EM_RESTART': '1',
22:36:49 INFO - 'NUMBER_OF_PROCESSORS': '8',
22:36:49 INFO - 'OS': 'Windows_NT',
22:36:49 INFO - 'OURDRIVE': 'C:',
22:36:49 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
22:36:49 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
22:36:49 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
22:36:49 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
22:36:49 INFO - 'PROCESSOR_LEVEL': '6',
22:36:49 INFO - 'PROCESSOR_REVISION': '1e05',
22:36:49 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
22:36:49 INFO - 'PROGRAMFILES': 'C:\\Program Files',
22:36:49 INFO - 'PROMPT': '$P$G',
22:36:49 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
22:36:49 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
22:36:49 INFO - 'PUBLIC': 'C:\\Users\\Public',
22:36:49 INFO - 'PWD': 'C:\\slave\\test',
22:36:49 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
22:36:49 INFO - 'SLAVEDIR': 'C:\\slave\\',
22:36:49 INFO - 'SYSTEMDRIVE': 'C:',
22:36:49 INFO - 'SYSTEMROOT': 'C:\\windows',
22:36:49 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
22:36:49 INFO - 'TEST1': 'testie',
22:36:49 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
22:36:49 INFO - 'USERDOMAIN': 'T-W732-IX-068',
22:36:49 INFO - 'USERNAME': 'cltbld',
22:36:49 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
22:36:49 INFO - 'WINDIR': 'C:\\windows',
22:36:49 INFO - 'WINDOWS_TRACING_FLAGS': '3',
22:36:49 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
22:36:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
22:36:49 INFO - Ignoring indexes: https://pypi.python.org/simple/
22:36:49 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
22:36:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gdalgv-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
22:36:49 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
22:36:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wyenur-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
22:36:49 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
22:36:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jt3x1x-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
22:36:49 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
22:36:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-1xe7n4-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
22:36:49 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
22:36:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-nv91hh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
22:36:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
22:36:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pwxts1-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
22:36:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
22:36:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zdxryj-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
22:36:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
22:36:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bvm7pk-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
22:36:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
22:36:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-n7une5-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
22:36:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
22:36:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-2secpn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
22:36:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
22:36:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gyjpi6-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
22:36:51 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
22:36:51 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-x0wswi-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
22:36:51 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
22:36:51 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-prr2x9-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
22:36:51 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
22:36:51 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-nb7o_o-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
22:36:51 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
22:36:51 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ckv8ug-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
22:36:51 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
22:36:51 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-eltwsy-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
22:36:51 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
22:36:51 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-luneop-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
22:36:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
22:36:51 INFO - Running setup.py install for manifestparser
22:36:52 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts
22:36:52 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts
22:36:52 INFO - Running setup.py install for mozcrash
22:36:52 INFO - Running setup.py install for mozdebug
22:36:53 INFO - Running setup.py install for mozdevice
22:36:53 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts
22:36:53 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts
22:36:53 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts
22:36:53 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts
22:36:53 INFO - Running setup.py install for mozfile
22:36:54 INFO - Running setup.py install for mozhttpd
22:36:54 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts
22:36:54 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts
22:36:54 INFO - Running setup.py install for mozinfo
22:36:54 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts
22:36:54 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts
22:36:54 INFO - Running setup.py install for mozInstall
22:36:55 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts
22:36:55 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts
22:36:55 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts
22:36:55 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts
22:36:55 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts
22:36:55 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts
22:36:55 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts
22:36:55 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts
22:36:55 INFO - Running setup.py install for mozleak
22:36:55 INFO - Running setup.py install for mozlog
22:36:55 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts
22:36:55 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts
22:36:56 INFO - Running setup.py install for moznetwork
22:36:56 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts
22:36:56 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts
22:36:56 INFO - Running setup.py install for mozprocess
22:36:57 INFO - Running setup.py install for mozprofile
22:36:57 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts
22:36:57 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts
22:36:57 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts
22:36:57 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts
22:36:57 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts
22:36:57 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts
22:36:58 INFO - Running setup.py install for mozrunner
22:36:58 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts
22:36:58 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts
22:36:58 INFO - Running setup.py install for mozscreenshot
22:36:59 INFO - Running setup.py install for moztest
22:36:59 INFO - Running setup.py install for mozversion
22:36:59 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts
22:36:59 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts
22:36:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion
22:36:59 INFO - Cleaning up...
22:36:59 INFO - Return code: 0
22:36:59 INFO - Installing None into virtualenv C:\slave\test\build/venv
22:36:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
22:36:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
22:36:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
22:36:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
22:36:59 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 0x01801640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018A9E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017AB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C8E90>, '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 0x0121A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0197E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-068', 'USERDOMAIN': 'T-W732-IX-068', '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-068', '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
22:36:59 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
22:36:59 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
22:36:59 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
22:36:59 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
22:36:59 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
22:36:59 INFO - 'COMPUTERNAME': 'T-W732-IX-068',
22:36:59 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
22:36:59 INFO - 'CYGWINBASE': 'C:\\cygwin',
22:36:59 INFO - 'DCLOCATION': 'SCL3',
22:36:59 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
22:36:59 INFO - 'FP_NO_HOST_CHECK': 'NO',
22:36:59 INFO - 'HOMEDRIVE': 'C:',
22:36:59 INFO - 'HOMEPATH': '\\Users\\cltbld',
22:36:59 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
22:36:59 INFO - 'KTS_VERSION': '1.19c',
22:36:59 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
22:36:59 INFO - 'LOGONSERVER': '\\\\T-W732-IX-068',
22:36:59 INFO - 'MONDIR': 'C:\\Monitor_config\\',
22:36:59 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
22:36:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
22:36:59 INFO - 'MOZ_NO_REMOTE': '1',
22:36:59 INFO - 'NO_EM_RESTART': '1',
22:36:59 INFO - 'NUMBER_OF_PROCESSORS': '8',
22:36:59 INFO - 'OS': 'Windows_NT',
22:36:59 INFO - 'OURDRIVE': 'C:',
22:36:59 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
22:36:59 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
22:36:59 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
22:36:59 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
22:36:59 INFO - 'PROCESSOR_LEVEL': '6',
22:36:59 INFO - 'PROCESSOR_REVISION': '1e05',
22:36:59 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
22:36:59 INFO - 'PROGRAMFILES': 'C:\\Program Files',
22:36:59 INFO - 'PROMPT': '$P$G',
22:36:59 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
22:36:59 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
22:36:59 INFO - 'PUBLIC': 'C:\\Users\\Public',
22:36:59 INFO - 'PWD': 'C:\\slave\\test',
22:36:59 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
22:36:59 INFO - 'SLAVEDIR': 'C:\\slave\\',
22:36:59 INFO - 'SYSTEMDRIVE': 'C:',
22:36:59 INFO - 'SYSTEMROOT': 'C:\\windows',
22:36:59 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
22:36:59 INFO - 'TEST1': 'testie',
22:36:59 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
22:36:59 INFO - 'USERDOMAIN': 'T-W732-IX-068',
22:36:59 INFO - 'USERNAME': 'cltbld',
22:36:59 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
22:36:59 INFO - 'WINDIR': 'C:\\windows',
22:36:59 INFO - 'WINDOWS_TRACING_FLAGS': '3',
22:36:59 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
22:36:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
22:37:00 INFO - Ignoring indexes: https://pypi.python.org/simple/
22:37:00 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
22:37:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-x5t3xk-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
22:37:00 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))
22:37:00 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
22:37:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-6tp_e6-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
22:37:00 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))
22:37:00 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
22:37:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-6cbjw8-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
22:37:00 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))
22:37:00 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
22:37:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jlfpzv-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
22:37:01 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))
22:37:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
22:37:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dqof7g-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
22:37:01 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))
22:37:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
22:37:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-4gjotm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
22:37:01 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))
22:37:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
22:37:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vridtt-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
22:37:01 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))
22:37:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
22:37:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-9atcfu-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
22:37:01 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))
22:37:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
22:37:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wwp74n-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
22:37:01 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))
22:37:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
22:37:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-tu8s0w-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
22:37:01 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))
22:37:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
22:37:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-o7fjap-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
22:37:01 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))
22:37:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
22:37:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kttecu-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
22:37:02 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))
22:37:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
22:37:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vokdmt-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
22:37:02 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))
22:37:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
22:37:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-9zczwn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
22:37:02 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))
22:37:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
22:37:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-d1vbng-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
22:37:02 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))
22:37:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
22:37:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cnr3rw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
22:37:02 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))
22:37:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
22:37:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gqbqr4-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
22:37:02 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))
22:37:02 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))
22:37:02 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))
22:37:02 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))
22:37:02 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))
22:37:02 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))
22:37:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10))
22:37:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
22:37:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
22:37:03 INFO - Downloading blessings-1.6.tar.gz
22:37:03 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings
22:37:04 INFO - Installing collected packages: blessings
22:37:04 INFO - Running setup.py install for blessings
22:37:04 INFO - Successfully installed blessings
22:37:04 INFO - Cleaning up...
22:37:07 INFO - Return code: 0
22:37:07 INFO - Done creating virtualenv C:\slave\test\build/venv.
22:37:07 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
22:37:07 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
22:37:07 INFO - Reading from file tmpfile_stdout
22:37:07 INFO - Using _rmtree_windows ...
22:37:07 INFO - Using _rmtree_windows ...
22:37:07 INFO - Current package versions:
22:37:07 INFO - blessings == 1.6
22:37:07 INFO - blobuploader == 1.2.4
22:37:07 INFO - docopt == 0.6.1
22:37:07 INFO - functools32 == 3.2.3-2
22:37:07 INFO - jsonschema == 2.5.1
22:37:07 INFO - manifestparser == 1.1
22:37:07 INFO - mozInstall == 1.12
22:37:07 INFO - mozcrash == 0.17
22:37:07 INFO - mozdebug == 0.1
22:37:07 INFO - mozdevice == 0.48
22:37:07 INFO - mozfile == 1.2
22:37:07 INFO - mozhttpd == 0.7
22:37:07 INFO - mozinfo == 0.9
22:37:07 INFO - mozleak == 0.1
22:37:07 INFO - mozlog == 3.4
22:37:07 INFO - moznetwork == 0.27
22:37:07 INFO - mozprocess == 0.23
22:37:07 INFO - mozprofile == 0.28
22:37:07 INFO - mozrunner == 6.12
22:37:07 INFO - mozscreenshot == 0.1
22:37:07 INFO - mozsystemmonitor == 0.3
22:37:07 INFO - moztest == 0.7
22:37:07 INFO - mozversion == 1.4
22:37:07 INFO - psutil == 3.1.1
22:37:07 INFO - requests == 1.2.3
22:37:07 INFO - Installing None into virtualenv C:\slave\test\build/venv
22:37:07 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')]}
22:37:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
22:37:07 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')]}
22:37:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
22:37:07 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 0x01801640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018A9E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017AB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C8E90>, '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 0x0121A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0197E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-068', 'USERDOMAIN': 'T-W732-IX-068', '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-068', '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
22:37:07 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
22:37:07 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
22:37:07 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
22:37:07 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
22:37:07 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
22:37:07 INFO - 'COMPUTERNAME': 'T-W732-IX-068',
22:37:07 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
22:37:07 INFO - 'CYGWINBASE': 'C:\\cygwin',
22:37:07 INFO - 'DCLOCATION': 'SCL3',
22:37:07 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
22:37:07 INFO - 'FP_NO_HOST_CHECK': 'NO',
22:37:07 INFO - 'HOMEDRIVE': 'C:',
22:37:07 INFO - 'HOMEPATH': '\\Users\\cltbld',
22:37:07 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
22:37:07 INFO - 'KTS_VERSION': '1.19c',
22:37:07 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
22:37:07 INFO - 'LOGONSERVER': '\\\\T-W732-IX-068',
22:37:07 INFO - 'MONDIR': 'C:\\Monitor_config\\',
22:37:07 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
22:37:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
22:37:07 INFO - 'MOZ_NO_REMOTE': '1',
22:37:07 INFO - 'NO_EM_RESTART': '1',
22:37:07 INFO - 'NUMBER_OF_PROCESSORS': '8',
22:37:07 INFO - 'OS': 'Windows_NT',
22:37:07 INFO - 'OURDRIVE': 'C:',
22:37:07 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;',
22:37:07 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
22:37:07 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
22:37:07 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
22:37:07 INFO - 'PROCESSOR_LEVEL': '6',
22:37:07 INFO - 'PROCESSOR_REVISION': '1e05',
22:37:07 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
22:37:07 INFO - 'PROGRAMFILES': 'C:\\Program Files',
22:37:07 INFO - 'PROMPT': '$P$G',
22:37:07 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
22:37:07 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
22:37:07 INFO - 'PUBLIC': 'C:\\Users\\Public',
22:37:07 INFO - 'PWD': 'C:\\slave\\test',
22:37:07 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
22:37:07 INFO - 'SLAVEDIR': 'C:\\slave\\',
22:37:07 INFO - 'SYSTEMDRIVE': 'C:',
22:37:07 INFO - 'SYSTEMROOT': 'C:\\windows',
22:37:07 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
22:37:07 INFO - 'TEST1': 'testie',
22:37:07 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
22:37:07 INFO - 'USERDOMAIN': 'T-W732-IX-068',
22:37:07 INFO - 'USERNAME': 'cltbld',
22:37:07 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
22:37:07 INFO - 'WINDIR': 'C:\\windows',
22:37:07 INFO - 'WINDOWS_TRACING_FLAGS': '3',
22:37:07 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
22:37:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
22:37:08 INFO - Ignoring indexes: https://pypi.python.org/simple/
22:37:08 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))
22:37:08 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))
22:37:08 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))
22:37:08 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))
22:37:08 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))
22:37:08 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))
22:37:08 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))
22:37:08 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))
22:37:08 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))
22:37:08 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))
22:37:08 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))
22:37:08 INFO - Cleaning up...
22:37:08 INFO - Return code: 0
22:37:08 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv
22:37:08 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')]}
22:37:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
22:37:08 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')]}
22:37:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
22:37:08 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 0x01801640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018A9E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017AB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C8E90>, '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 0x0121A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0197E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-068', 'USERDOMAIN': 'T-W732-IX-068', '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-068', '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
22:37:08 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
22:37:08 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
22:37:08 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
22:37:08 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
22:37:08 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
22:37:08 INFO - 'COMPUTERNAME': 'T-W732-IX-068',
22:37:08 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
22:37:08 INFO - 'CYGWINBASE': 'C:\\cygwin',
22:37:08 INFO - 'DCLOCATION': 'SCL3',
22:37:08 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
22:37:08 INFO - 'FP_NO_HOST_CHECK': 'NO',
22:37:08 INFO - 'HOMEDRIVE': 'C:',
22:37:08 INFO - 'HOMEPATH': '\\Users\\cltbld',
22:37:08 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
22:37:08 INFO - 'KTS_VERSION': '1.19c',
22:37:08 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
22:37:08 INFO - 'LOGONSERVER': '\\\\T-W732-IX-068',
22:37:08 INFO - 'MONDIR': 'C:\\Monitor_config\\',
22:37:08 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
22:37:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
22:37:08 INFO - 'MOZ_NO_REMOTE': '1',
22:37:08 INFO - 'NO_EM_RESTART': '1',
22:37:08 INFO - 'NUMBER_OF_PROCESSORS': '8',
22:37:08 INFO - 'OS': 'Windows_NT',
22:37:08 INFO - 'OURDRIVE': 'C:',
22:37:08 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;',
22:37:08 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
22:37:08 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
22:37:08 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
22:37:08 INFO - 'PROCESSOR_LEVEL': '6',
22:37:08 INFO - 'PROCESSOR_REVISION': '1e05',
22:37:08 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
22:37:08 INFO - 'PROGRAMFILES': 'C:\\Program Files',
22:37:08 INFO - 'PROMPT': '$P$G',
22:37:08 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
22:37:08 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
22:37:08 INFO - 'PUBLIC': 'C:\\Users\\Public',
22:37:08 INFO - 'PWD': 'C:\\slave\\test',
22:37:08 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
22:37:08 INFO - 'SLAVEDIR': 'C:\\slave\\',
22:37:08 INFO - 'SYSTEMDRIVE': 'C:',
22:37:08 INFO - 'SYSTEMROOT': 'C:\\windows',
22:37:08 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
22:37:08 INFO - 'TEST1': 'testie',
22:37:08 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
22:37:08 INFO - 'USERDOMAIN': 'T-W732-IX-068',
22:37:08 INFO - 'USERNAME': 'cltbld',
22:37:08 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
22:37:08 INFO - 'WINDIR': 'C:\\windows',
22:37:08 INFO - 'WINDOWS_TRACING_FLAGS': '3',
22:37:08 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
22:37:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
22:37:08 INFO - Ignoring indexes: https://pypi.python.org/simple/
22:37:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages
22:37:08 INFO - Cleaning up...
22:37:08 INFO - Return code: 0
22:37:08 INFO - Running post-action listener: _resource_record_post_action
22:37:08 INFO - Running post-action listener: _start_resource_monitoring
22:37:08 INFO - Starting resource monitoring.
22:37:08 INFO - [mozharness: 2016-09-27 05:37:08.627000Z] Finished create-virtualenv step (success)
22:37:08 INFO - [mozharness: 2016-09-27 05:37:08.627000Z] Running install step.
22:37:08 INFO - Running pre-action listener: _resource_record_pre_action
22:37:08 INFO - Running main action method: install
22:37:08 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
22:37:08 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
22:37:08 INFO - Reading from file tmpfile_stdout
22:37:08 INFO - Using _rmtree_windows ...
22:37:08 INFO - Using _rmtree_windows ...
22:37:08 INFO - Detecting whether we're running mozinstall >=1.0...
22:37:08 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h']
22:37:08 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h
22:37:09 INFO - Reading from file tmpfile_stdout
22:37:09 INFO - Output received:
22:37:09 INFO - Usage: mozinstall-script.py [options] installer
22:37:09 INFO - Options:
22:37:09 INFO - -h, --help show this help message and exit
22:37:09 INFO - -d DEST, --destination=DEST
22:37:09 INFO - Directory to install application into. [default:
22:37:09 INFO - "C:\slave\test"]
22:37:09 INFO - --app=APP Application being installed. [default: firefox]
22:37:09 INFO - Using _rmtree_windows ...
22:37:09 INFO - Using _rmtree_windows ...
22:37:09 INFO - mkdir: C:\slave\test\build\application
22:37:09 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']
22:37:09 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
22:37:11 INFO - Reading from file tmpfile_stdout
22:37:11 INFO - Output received:
22:37:11 INFO - C:\slave\test\build\application\firefox\firefox.exe
22:37:11 INFO - Using _rmtree_windows ...
22:37:11 INFO - Using _rmtree_windows ...
22:37:11 INFO - Running post-action listener: _resource_record_post_action
22:37:11 INFO - [mozharness: 2016-09-27 05:37:11.363000Z] Finished install step (success)
22:37:11 INFO - [mozharness: 2016-09-27 05:37:11.364000Z] Running run-tests step.
22:37:11 INFO - Running pre-action listener: _resource_record_pre_action
22:37:11 INFO - Running main action method: run_tests
22:37:11 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version']
22:37:11 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version
22:37:11 INFO - Python 2.7.3
22:37:11 INFO - Return code: 0
22:37:11 INFO - grabbing minidump binary from tooltool
22:37:11 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')]}
22:37:11 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 0x018C8E90>, '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 0x0121A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0197E520>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1
22:37:11 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
22:37:11 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
22:37:11 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
22:37:11 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'...
22:37:13 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmpkidwne
22:37:13 INFO - INFO - File integrity verified, renaming tmpkidwne to win32-minidump_stackwalk.exe
22:37:13 INFO - Return code: 0
22:37:13 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755
22:37:13 INFO - mkdir: C:\slave\test\build\blobber_upload_dir
22:37:13 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir
22:37:13 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe
22:37:13 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir
22:37:13 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/M6BxDJNQRVaKb9MGEgyr_A/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-068', '--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
22:37:13 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --branchName Autoland --suite dromaeojs --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/M6BxDJNQRVaKb9MGEgyr_A/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-068 --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
22:37:13 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
22:37:13 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
22:37:13 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
22:37:13 INFO - 'COMPUTERNAME': 'T-W732-IX-068',
22:37:13 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
22:37:13 INFO - 'CYGWINBASE': 'C:\\cygwin',
22:37:13 INFO - 'DCLOCATION': 'SCL3',
22:37:13 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
22:37:13 INFO - 'FP_NO_HOST_CHECK': 'NO',
22:37:13 INFO - 'HOMEDRIVE': 'C:',
22:37:13 INFO - 'HOMEPATH': '\\Users\\cltbld',
22:37:13 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
22:37:13 INFO - 'KTS_VERSION': '1.19c',
22:37:13 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
22:37:13 INFO - 'LOGONSERVER': '\\\\T-W732-IX-068',
22:37:13 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir',
22:37:13 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe',
22:37:13 INFO - 'MONDIR': 'C:\\Monitor_config\\',
22:37:13 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
22:37:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
22:37:13 INFO - 'MOZ_NO_REMOTE': '1',
22:37:13 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir',
22:37:13 INFO - 'NO_EM_RESTART': '1',
22:37:13 INFO - 'NUMBER_OF_PROCESSORS': '8',
22:37:13 INFO - 'OS': 'Windows_NT',
22:37:13 INFO - 'OURDRIVE': 'C:',
22:37:13 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
22:37:13 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
22:37:13 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
22:37:13 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
22:37:13 INFO - 'PROCESSOR_LEVEL': '6',
22:37:13 INFO - 'PROCESSOR_REVISION': '1e05',
22:37:13 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
22:37:13 INFO - 'PROGRAMFILES': 'C:\\Program Files',
22:37:13 INFO - 'PROMPT': '$P$G',
22:37:13 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
22:37:13 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
22:37:13 INFO - 'PUBLIC': 'C:\\Users\\Public',
22:37:13 INFO - 'PWD': 'C:\\slave\\test',
22:37:13 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos',
22:37:13 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
22:37:13 INFO - 'SLAVEDIR': 'C:\\slave\\',
22:37:13 INFO - 'SYSTEMDRIVE': 'C:',
22:37:13 INFO - 'SYSTEMROOT': 'C:\\windows',
22:37:13 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
22:37:13 INFO - 'TEST1': 'testie',
22:37:13 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
22:37:13 INFO - 'USERDOMAIN': 'T-W732-IX-068',
22:37:13 INFO - 'USERNAME': 'cltbld',
22:37:13 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
22:37:13 INFO - 'WINDIR': 'C:\\windows',
22:37:13 INFO - 'WINDOWS_TRACING_FLAGS': '3',
22:37:13 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
22:37:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
22:37:13 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/M6BxDJNQRVaKb9MGEgyr_A/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-068', '--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
22:37:14 INFO - mozversion application_buildid: 20160926183005
22:37:14 INFO - mozversion application_changeset: 19e3f7722664812216634b38a72874514e57ee7a
22:37:14 INFO - mozversion application_display_name: Nightly
22:37:14 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
22:37:14 INFO - mozversion application_name: Firefox
22:37:14 INFO - mozversion application_remotingname: firefox
22:37:14 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland
22:37:14 INFO - mozversion application_vendor: Mozilla
22:37:14 INFO - mozversion application_version: 52.0a1
22:37:14 INFO - mozversion platform_buildid: 20160926183005
22:37:14 INFO - mozversion platform_changeset: 19e3f7722664812216634b38a72874514e57ee7a
22:37:14 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland
22:37:14 INFO - mozversion platform_version: 52.0a1
22:37:14 INFO - using testdate: 1474954634
22:37:14 INFO - actual date: 1474954634
22:37:14 INFO - starting webserver on 'localhost:49892'
22:37:14 INFO - SUITE-START | Running 2 tests
22:37:14 INFO - TEST-START | dromaeo_css
22:37:14 INFO - operating with platform_type : w7_
22:37:14 INFO - Initialising browser for dromaeo_css test...
22:37:14 INFO - TEST-INFO | started process 4072 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmparznpv\profile http://localhost:49892/getInfo.html)
22:37:22 INFO - PROCESS | 4072 | __metrics Screen width/height:1600/1200
22:37:22 INFO - PROCESS | 4072 | colorDepth:24
22:37:22 INFO - PROCESS | 4072 | Browser inner width/height: 1010/674
22:37:22 INFO - PROCESS | 4072 | __metrics
22:37:23 INFO - PROCESS | 4072 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows"
22:37:23 INFO - PROCESS | 4072 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
22:37:24 INFO - TEST-INFO | 4072: exit 0
22:37:24 INFO - Browser initialized.
22:37:24 INFO - Running cycle 1/1 for dromaeo_css test...
22:37:24 INFO - TEST-INFO | started process 3740 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmparznpv\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1)
22:37:25 INFO - PROCESS | 3740 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0
22:37:36 INFO - PROCESS | 3740 | RSS: Main: 124588032
22:37:36 INFO - PROCESS | 3740 |
22:38:27 INFO - PROCESS | 3740 | 1474954707078 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]
22:40:35 INFO - PROCESS | 3740 | Cycle 1(1): loaded http://localhost:49892/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49892/tests/dromaeo/cssquery-ext.html)
22:40:37 INFO - PROCESS | 3740 | RSS: Main: 355942400
22:40:37 INFO - PROCESS | 3740 |
22:43:40 INFO - PROCESS | 3740 | Cycle 1(1): loaded http://localhost:49892/tests/dromaeo/cssquery-ext.html (next: http://localhost:49892/tests/dromaeo/cssquery-jquery.html)
22:43:41 INFO - PROCESS | 3740 | RSS: Main: 196022272
22:43:41 INFO - PROCESS | 3740 |
22:46:42 INFO - PROCESS | 3740 | Cycle 1(1): loaded http://localhost:49892/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49892/tests/dromaeo/cssquery-mootools.html)
22:46:43 INFO - PROCESS | 3740 | RSS: Main: 148832256
22:46:43 INFO - PROCESS | 3740 |
22:49:45 INFO - PROCESS | 3740 | Cycle 1(1): loaded http://localhost:49892/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49892/tests/dromaeo/cssquery-prototype.html)
22:49:46 INFO - PROCESS | 3740 | RSS: Main: 145592320
22:49:46 INFO - PROCESS | 3740 |
22:52:49 INFO - PROCESS | 3740 | Cycle 1(1): loaded http://localhost:49892/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49892/tests/dromaeo/cssquery-yui.html)
22:52:49 INFO - PROCESS | 3740 | RSS: Main: 132624384
22:52:49 INFO - PROCESS | 3740 |
22:55:52 INFO - PROCESS | 3740 | Cycle 1(1): loaded http://localhost:49892/tests/dromaeo/cssquery-yui.html (next: http://localhost:49892/tests/dromaeo/cssquery-dojo.html)
22:55:52 INFO - PROCESS | 3740 | RSS: Main: 182829056
22:55:52 INFO - PROCESS | 3740 |
22:55:52 INFO - PROCESS | 3740 | __start_tp_report
22:55:52 INFO - PROCESS | 3740 | _x_x_mozilla_page_load
22:55:52 INFO - PROCESS | 3740 | _x_x_mozilla_page_load_details
22:55:52 INFO - PROCESS | 3740 | |i|pagename|runs|
22:55:52 INFO - PROCESS | 3740 | |0;dojo.html;2722.646056012718;2734.4531093781247;2744.3002034481206;2757.1866299441663;2763.240108970033;4568.314752787083;4651.976740116299;4668.76656167192;4747.406574178228;4769.618430525558;4367.1265746850595;4635.235186194274;4657.5575320344515;4798.4961579034225;4806.519348065201;4133.173365326931;4151.958480415187;4157.542670306271;4182.16356728654;4190.20386126635;4057.5333836608725;4111.814968326433;4123.958760412387;4206.852760153395;4220.050488640062;4095.6109169628985;4133.46264985553;4199.664026877843;4207.747535147885;4213.367994800774;4103.179364127186;4112.938305925413;4184.4141820145205;4192.685548583838;4201.474815648044;3864.401017842239;3921.156951255494;3971.98014009931;4097.692673049503;4105.0763578194965;4393.5606439356125;4493.707908985905;4641.303804429329;4660.603848672867;4664.5335466453425;4757.809687612491;4891.584215341701;4954.232094025432;4999.675021123615;5001.899962000776;4881.8535443936735;4916.680415772963;4923.212286034217;5037.672551284155;5037.79848806051;4516.570925762047;4552.089582083597;4595.517470665599;4625.098105869357;4659.790309436084;4808.543188397097;4934.53121953421;4999;5057.747112644353;5146.279520867081;4825.227963525819;4845.6608037437745;4896.069746748107;4929.704217746947;4932.704037757746;4576.88557786058;4884.877878053077;4901.240307752304;4922.507749225048;4957.677750946177;4838;4845.248986407112;4939.9259011114145;4940.333055037596;4943.530364615356;4868.756562171948;4917.557419832232;4957.950420495821;4959.677620954699;4963.404391472975;3290.3748287825238;3303.785253958485;3325.717314028286;3330.8834190803204;3362.2098806780386;2957.9704202958123;3048.7713421493477;3060.816351018946;3088.027271409499;3094.659587445379;4414.27164517851;4528;4669.696469729457;4795.592374648124;4813.687110337817;4786.856394308176;4833.05755377698;4885.6335774817035;4990.850274491771;5005.399352077774;4378.387025816388;4482.327650852333;4509.503954564995;4671.299305104194;4750.691205071728;4012.117334186474;4079.9592004079577;4126.257273690764;4150.6471949884;4157.875263742093;5146.588272938156;5169;5178;5178.067947769362;5192.532672059533;4960.925586116284;4961.355023846877;4969.055879382906;4971.254311853251;4973.900521989468;5032.245163225545;5107.259447380184;5188.040212560688;5209.661372010807;5251.054810134213;4856.732879691651;4901.068796928572;4920.31115643803;4922.680025798311;4927.260910863399;4917.237828136645;4944.332515110558;4955.752212389294;4967.925481117858;5108.208227724708;4846.39420072491;4863.513648635108;4940.827071052496;4942.703437793743;4965.975170124127;4761.833335833229;4768.356271903249;4783.593394561502;4821.300911367762;4942.728149951788;4459;4738.857834264978;4741.122892264942;4748.833790817304;4753.120672675566;4864.440589332272;4870.756462176806;4871.391076115486;4873.074115917964;4881.463039065629;4791.904161916812;4811.7834697438;4820.71075735457;4825.855224343275;4843.07981483517
22:55:52 INFO - PROCESS | 3740 | |1;ext.html;517.6919732759009;538.4507802041917;548.1175307754514;551.9172124181374;578.8032069096512;37098;40271.39592906105;40277.59722402782;40346.596534034725;40460.59539404605;40437.797811010976;40707.79646101773;40980.385294220614;41125;41147.38278925811;8241.299489543397;8283.95858020709;8299.91700082999;8352.16478352166;8444.915550844506;7996.280334769869;8321.83356332873;8382.742588611696;8397.790055248637;8401.579921003957;8110.310623597002;8179.468334558234;8404.1175676554;8418.242358187761;8434.283085937703;8155.836883262302;8280.8343833123;8391;8410.32717382608;8417.621207045697;8330.416870819045;8350.164983501601;8369.163083691643;8389.95805020977;8397.748067557923;37981.24037519262;38066.42900356497;38182;38204.61795382066;38243.808780955915;2522.432452698147;2526.1916186820226;2617.777488913445;2796.4407118576164;2859.5281778506524;30545.389092218033;30621.387572248652;30855.691443085507;30870.22824429385;31593.368132637217;3252.382047411008;3290.029441314794;3313.7349012079217;3322.9833850830832;3350.229447227127;319300;333847;335665;337046.99999999756;337728;51504.48495514997;53953;54022.45977540253;54023;54253;53994.460055398944;56009.439905600426;56216.15675764867;58184.4181558179;58756;14310;14488.492902748563;15161.696766064617;15162.545123646309;15187.544373668808;10265.2301077418;10786.73033174177;10832.620858269922;10844.240903136704;10895.83656245157;17079.65840683204;17121;17948.282068717366;18044.639107217783;18071.277148913898;7066.364027237573;7121.430285577141;7147.356737893615;7176.820579485555;7227.168875579376;720.7693538067873;759.7113097023096;835.3943391041446;835.7200337886876;840.6889450903083;27097.864510677715;27359.863200684267;27865;28049;28509.572356414672;773.3735674103881;899.2086963472234;903.5437104262305;924.611663101499;959.8128364968765;13999;14526.709465810625;14562.927185364006;14660.413583456755;14788.186649734153;512753;534306;534795;537549;540863;48064.03871922542;49125;49210;49803.25295120576;49885;1013.0882206014646;1021.0555236406294;1066.4188017530464;1100.2848148703247;1140.680609429361;27049.729502705508;28124.57813132764;28289;28527;28737.56893646639;41773;42544.78727606342;42810;44166.55833441753;44233.55766442423;415870;418241;423243;424216;425442;36513.63486365102;37916.81041594885;40038.599614003484;40717.389239160846;41266.58733412628;41288.38067428931;41869.79065104655;43632;44156.337654934556;44545;36832.44751328679;37665.81167094147;38030.619693802706;38347.424788627635;38434.80782596069;36795.81602091972;36808.44787328246;37491.063438338584;37898.86303410902;38136.427953581275;35254.823725882234;35325;35370.46944295786;35394.646053540164;35701;31980.52029219517;32891;33211;33516;34158.462869171446;24329.39176520601;24956.625650614893;24987.250382488553;25062.874685626455;25372.74627253704
22:55:52 INFO - PROCESS | 3740 | |2;jquery.html;274.8845484896343;302.4887939382443;304.65421746317935;308.7961945116222;310.9502479603264;12407;18260.90869545652;18555.628887422245;18862.811371886277;19347.516312092204;17456.556082148476;17601.7359739604;17642.470725878244;17863.55341116473;17916.37292694756;5267.23625074364;5307.495787900162;5490.121580547113;5495.340559132909;5495.780168793243;5242.3184985951775;5305.522502974732;5417.6207665463435;5451.536619387337;5452.291202143716;5237.502437268454;5369.543588794957;5394.78420863167;5403.972980135113;5405.810796622119;5198.506141916512;5222.268882356473;5326.627136100456;5331.2003199519995;5379.273798037274;4626.8149274029;4651.488336283006;4657.347971284023;4669.369635099252;4674.158651442739;22054.448638783997;22134;22451.550968980693;22543.77456225433;22568.66147007796;1735.3665911942176;1784.027704900832;1795.8204179581937;1809.2401191499603;1968.2323893681476;8007.919920800776;14208.147511149364;14369.281535923163;14669.413223471049;14814.259287035606;1732.159902447312;1742.3117868441896;1768.2661695396396;1798.8021317655048;1806;85384.57307713483;87566;87613.56193219057;87946.12053879508;88346.11653883444;9167.22078623311;9318.161365477139;9325.860112098324;9423.575939082757;9430.62277508892;10659.84010239847;11092.168087393316;11104.333739975627;12018.098642601839;12035.217710848941;7416.925830741731;7426.108866936002;7459.440541959375;7534.095908491016;7548.5470871747875;7645.923540764632;7652.196519365498;7676.155622881479;7682;7810.0237470316215;3856.1130939883424;3865.8260378283044;3876.534815822063;3887.3974533947285;3894.357431023906;1568.7254730422358;1616.0465325458035;1620.1089400829496;1632.7795747574164;1645.983540164583;809.1908091908092;809.2918696140877;827.3753316246195;873.5675840458887;891.8483857744199;17867.46397608048;17879.910600446914;17903.194356254;17980.19089140992;18584.510259619005;823.9052508961546;840.0801122770556;842.9704960326359;917.0691747875911;925.1627277314042;9706;9747.611835100446;10168.542415591168;10172.796544069077;10238.455715367229;370233;389578;390458;394473;395350;88102;89589.55205224063;91746;92109;92955.5352223248;811.0875265326507;823.1480417767701;824.2993455562844;828.8010877389383;828.842519921213;16157.75763363527;16268.430604928772;16470.83529164693;16718.41485548;16739.163041847616;42314;42431.3635295477;43493.78253108714;44207.77896110499;44578.77710611426;64253.67873160604;65811.34188658051;66223.33776662171;66700.99948500679;67364.6631766838;7111.679974401268;7144.714211431485;7148.964255178691;7170.39051680613;7179.605121718147;8853.51305678194;11006.339619622848;12999.545015924397;13132.212067275994;13564.660883477993;10876.836847447454;11085.445727713743;11146.665600032013;11181.105511559219;11297.43512824372;10730.8926910733;10832.295900766425;10871.782564348827;11079.778404431705;11324;11397.772044559228;11624.593139240085;11638.70903227426;11777.822217778154;11844.052475801744;6154.446099851035;6402.167718196604;6446.742130314922;6494.545381823227;6553.574017689026;8876.467411955045;11059.391733454737;11208.159388045766;11284.887151128383;11293.096552275963
22:55:52 INFO - PROCESS | 3740 | |3;mootools.html;1047.8952104789516;1092.9453527323633;1093.1965005720795;1191.7318603314257;1406.1492796857895;6112.480439162671;6837.418819400351;7040.295970402957;7048.647567621624;7053.717851285949;7002.5098243123;7076.7169313227405;7121.4658900582535;7136.85726285474;7137.678804453799;1897.402318269744;1914.2343062774862;1915.3200613782117;1916.750822393087;1917.6835822089338;1874.7469091672654;1905.0474762618692;1909.9045047747627;1919.8176173263519;1955.071341112974;1912.4931893048354;1914;1931.0923865783059;1931.2661188748257;1933.197722944983;1825.543614096476;1855.7494738210303;1862.7019676851774;1866.7386565880788;1871.2421469304886;1876.9718454223062;1882.3694062489083;1887.7074053521726;1889.1498825528429;1896.6396384686866;6827.249002609709;6870.381665650116;6894.724211031553;6908.792736217922;6975.755848545327;3979.462772525701;4091.1408604193157;4094.385842123705;4103.8974025649295;4107.507099148122;2687.3550347916553;2742.3007133180913;2743.2593199835933;2765.128984369917;2766.5158597245604;787.8700014497658;843.9198276163754;848.3764433141636;848.6181218451721;850.0267194062764;8899.59951802164;8931.464112153226;8936.508492032935;8936.821263574757;8970.192682658591;3349.112485191426;3383.543221665093;3435.2442462658173;3453.8445769939913;3453.8445769940413;7144.749933752398;7152.284771522806;7157.141143062866;7157.642117894085;7158.498905076594;3961.207758448322;3965.5637879833193;3998.9400158997637;4018.457508236409;4027.275090483741;3360.764746467724;3472.2708231271213;3816.236752649426;3835.4822099016274;3835.4822099016833;2761.8342899426098;2834.7307005834414;2835.333696581302;2927.2389178813237;3008.4283986042583;893.4148132972913;896.6010125494223;899.9640014399482;905.1446383167975;911.5578944212052;765.7166848266177;772.7527191298731;781.7146741439334;785.2147852147853;809.7611204694512;4567.520410356931;4573.451185857718;4706.694064885772;4758.381410416624;4781.6891902026255;726.2773540327408;754.8301632132836;757.3448966643814;781.6443518199205;790.6402586823096;3161.6996385343355;3178.825164615922;3180.6024246969127;3186.3627274545183;3211.951820722691;8062.113167551564;8351.1648835116;8359.080501144868;8365.707200247962;8366.958165209135;8114.269715725613;8293.33653307734;8304.211099945496;8304.335653147733;8327.37544684139;6567.67161641916;6608.636524991076;6639.734410623521;6640.369164929324;6643.933560664332;4487.147441986012;4601.171789077998;4611.953880461287;4677.900693336995;4762.547557981986;7526.774196774106;7779.416543759127;7830.569318687527;7866.960665196638;7871.48835325702;7959.204079592226;8037.437379383621;8044.115147333906;8045.8735776992435;8049;6131.754729810936;6214.844628884314;6224.937750622617;6227.283862355887;6241.438270555672;5993.31076926159;6527.9020814688765;6804.21751498584;6810.62541560219;6889.827754306182;5549.278593782782;6094.299155597163;6106.08408738675;6130.417610327012;6206.937930620636;6074.453299203093;6150.600210986271;6172.722227499684;6205.410486003822;6206.224221972254;5015.3480047593575;5015.548600625962;5124.871878203075;5183.455737147545;5236.659617124875;4022.6178513041214;4203.705740598251;4224.852130175429;4228.682848786292;4289.6139347458875;4142.937855932103;4183.916321673611;4237.8304867804945;4302.849400270976;4421.513633500376
22:55:52 INFO - PROCESS | 3740 | |4;prototype.html;241.12952242404904;242.48713969953548;247.4000548669476;248.6978321339572;250.87456271864068;4935.77788999495;5059.3169922060515;5072.999999999995;5117.513836185561;5132.717700526469;4837.129316722989;4980.900381992358;5017.64876458648;5018.172001619729;5042.193249080139;3567.94648080279;3710.9628903711023;3713.9071523212;3716.4796928429905;3717.75834570752;3563.501109844624;3648.854045838163;3703.462997865308;3708.814559272039;3710.4063349864027;3562.5190599269154;3650.1787097902893;3655.1775850433705;3658.9451158232646;3662.780233186004;3626.3653860574295;3646.562412510489;3649.124210189562;3652.086978255436;3662.505561749156;3036.4079004594114;3146.8671278340007;3146.9685303146903;3147.59081319427;3149.7480201583817;4853.927191092136;4882.584980276681;4897.14299997499;4900.387451568554;4915.9754201229125;4163.146554956226;4170;4246.617804397619;4266;4276.807543660511;4982.9252561211615;5168.767405466763;5201.583873290166;5202.323697919284;5202.869928251786;3482.6765828985076;3486.267883744417;3492.5634295713035;3493.7379696522607;3496.685298323138;5410.945890541084;5457.508824205841;5468.890622187534;5472.733719890703;5475.4524547545325;3359.714424273915;3473.89578312651;3519.9648003519637;3561.9821900890847;3595.173110184672;3530.6292839252023;3552.609212986569;3630.9273814523563;3632.20091579852;3643.4716966039778;4249.2563801334645;4252.29837076885;4271.188474189894;4276.989814787045;4277.187334406515;5244.265802787613;5251.894962100737;5258.2375555544995;5267.420583735786;5272.497338258578;1184.7275126720901;1206.197878410847;1207.9094067944764;1228.9385530723607;1255.805350170725;795.3001358804215;802.0976401548259;810.9635066422026;835.7826964989147;840.920112589303;5085.160948443539;5089.3638295213095;5103;5117.744112794346;5124.538791508782;5366;5371.838844834661;5374.220737992969;5374.973125134427;5380.681732975446;4991.251312303184;5109.131447653907;5111.182210846247;5124.538791508782;5132;2878.208492664534;2920.532714765627;2921.2404774758293;2922.6931172227037;2933.1933718227233;5763.2507773989955;5784.566157538202;5808.506276966421;5817.912731309034;5821.446962538552;5491.148871924858;5610.102383618601;5610.298712660918;5613.803516876971;5613.915791263053;5635.337575415262;5822.970885145547;5826.708664566755;5827.096799996008;5833.241678581757;5465.672059676433;5517.5102722265565;5518.530924871431;5521.779128834802;5523.502884740393;5457.69982650958;5459.890802184013;5464.699441530754;5465.262189604353;5474.549244450303;5545.584081194007;5629.352624448241;5787.2187254720075;5817.592768506164;5835.9708201458725;3309.2057906102846;3478.7738796978115;3486.564179477565;3487.877924272638;3488.267463832522;5543.556515478671;5546.944530554642;5555.638883472561;5562.443755624405;5565.443455654402;5369.973150134224;5382.273393091882;5387.407385187705;5392.191171324333;5396.78412863481;5231.659942103751;5352.812651557177;5353.518183363516;5364.839054828361;5372;5175.404828444777;5215.791368345223;5217.556507696737;5218.791248350023;5226.503482169188;5116.948830511647;5162.586993040472;5277.4194838566955;5362.812301569426;5405.0270951227585;5304.230886521355;5306.151015837448;5311.548518375981;5318.840434786963;5319.202119682079
22:55:52 INFO - PROCESS | 3740 | |5;yui.html;607.65060090448;612.8284080457471;635.9300476947535;645.46748932131;700.8282970672185;13393.464261429543;13949.651258718537;13982.510612128577;14020.859791402108;14044;12856.807147892789;13001.674958126052;13489.730205395887;13523.188608683486;13556.322183890816;626.3392121312025;627.4227710506323;638.741309769544;641.5957946493702;643.012975083245;516.377764793425;528.7567718849315;631.059721015688;644.4071454262066;651.0137142229526;675.9729610815562;680.4658343200589;681.2404169351163;681.3152781454631;682.5085938124543;669.5580916595072;681.2574294019518;681.3629256645053;681.366329313738;681.3765404654735;392.21361170852543;398.0407218603181;467.5791787391341;478.46889952153083;479.1949524798337;13302;13339.19964802115;13340;13359.1316564423;13360.398782054734;13086.34568271592;13178.868211317957;13282.335883205898;13468.730625387536;13676.931615341959;12242.387880606024;12682.873171268262;12731;12849;12854.485820567166;3423.589169299675;3456.7753096048928;3516.753827232095;3530.8587656493964;4334.068175342285;76037.61981190114;76345.61827190829;76402.23597764062;77802.61098694526;78503.60748196223;9548.952255238679;9943.26560195952;10148.289619726696;10201.438920859275;10345.172386209086;16506.83493165077;16543.83456165447;16564.6687066258;16601.667966640598;16617.833821661872;7202.639868006579;7337.0828646419195;7445.143808462096;7519.8496030079095;7556.886646700304;4077.6941729370415;4133.46264985556;4172.83308667656;4233.724807887539;4251.766152861623;2878.956815647767;2975.0331142378814;2991.401719656034;2992.07245753817;2992.685767994373;545.2257793932625;552.7540244591198;559.1752165555773;559.5439716630933;560.3751816724323;504.63666160364477;627.1815281058263;629.2480485819402;695.9547629404072;712.8823744082272;8849.292056635453;9043.231325337289;9334.299927505464;9549.713508594754;9947;532.3398985258252;644.5520363347522;650.200253687957;684.4455990647591;715.413361043939;4482.39487669167;4623.190941585209;4651.37206477128;4738.502457242009;4794.184988551954;108490.45754771333;109129;111883.44058279657;111934.44032779784;112587.43706281416;95935;97076.51461742647;97346;99386.00613993767;100254.49872750735;83384.16615833885;83928.58035709782;86649;87565;87596.5620171895;8841.823163536565;9119.680811171576;9135.817283654422;9298.977112517483;9448;49425.75287123541;51695.741521292155;51793.482065180375;51841.481585185174;52524.94950101053;66811;71709.28290717026;74674.25325746673;74675.62662186872;75108.62445687737;10617.575296988034;10826.188035897181;10974.451277435937;11138;11152.665420037412;12769;12927.935360323138;12939.5471158509;12963.546275880299;13056.423998808767;11497.712557186138;11619.6514104577;11751.706207344885;12087.153899227322;12870.549530766379;11274.887251127711;11314.547418103513;11327.886721132683;11457.197996140523;11545.595904143314;10420.374777513374;10520.210984176065;10671.039606435457;10770.24608277413;11341.376224307742;10258.333208341433;10597.099246564128;10621.628243011766;10757.193210509087;10773.946130269298;9028.232600229054;9675.467849268358;9885.901140988786;9918.822555130708;10340.53467593975
22:55:52 INFO - PROCESS | 3740 | __end_tp_report
22:55:52 INFO - PROCESS | 3740 | __start_cc_report
22:55:52 INFO - PROCESS | 3740 | _x_x_mozilla_cycle_collect,2052
22:55:52 INFO - PROCESS | 3740 | __end_cc_report
22:55:52 INFO - PROCESS | 3740 | __startTimestamp1474955752435__endTimestamp
22:55:52 INFO - PROCESS | 3740 |
22:55:52 INFO - PROCESS | 3740 | ------- Summary: start -------
22:55:52 INFO - PROCESS | 3740 | Number of tests: 6
22:55:52 INFO - PROCESS | 3740 |
22:55:52 INFO - PROCESS | 3740 | [#0] dojo.html Cycles:165 Average:4550.79 Median:4791.90 stddev:581.93 (12.1%) stddev-sans-first:565.77
22:55:52 INFO - PROCESS | 3740 | Values: 2722.6 2734.5 2744.3 2757.2 2763.2 4568.3 4652.0 4668.8 4747.4 4769.6 4367.1 4635.2 4657.6 4798.5 4806.5 4133.2 4152.0 4157.5 4182.2 4190.2 4057.5 4111.8 4124.0 4206.9 4220.1 4095.6 4133.5 4199.7 4207.7 4213.4 4103.2 4112.9 4184.4 4192.7 4201.5 3864.4 3921.2 3972.0 4097.7 4105.1 4393.6 4493.7 4641.3 4660.6 4664.5 4757.8 4891.6 4954.2 4999.7 5001.9 4881.9 4916.7 4923.2 5037.7 5037.8 4516.6 4552.1 4595.5 4625.1 4659.8 4808.5 4934.5 4999.0 5057.7 5146.3 4825.2 4845.7 4896.1 4929.7 4932.7 4576.9 4884.9 4901.2 4922.5 4957.7 4838.0 4845.2 4939.9 4940.3 4943.5 4868.8 4917.6 4958.0 4959.7 4963.4 3290.4 3303.8 3325.7 3330.9 3362.2 2958.0 3048.8 3060.8 3088.0 3094.7 4414.3 4528.0 4669.7 4795.6 4813.7 4786.9 4833.1 4885.6 4990.9 5005.4 4378.4 4482.3 4509.5 4671.3 4750.7 4012.1 4080.0 4126.3 4150.6 4157.9 5146.6 5169.0 5178.0 5178.1 5192.5 4960.9 4961.4 4969.1 4971.3 4973.9 5032.2 5107.3 5188.0 5209.7 5251.1 4856.7 4901.1 4920.3 4922.7 4927.3 4917.2 4944.3 4955.8 4967.9 5108.2 4846.4 4863.5 4940.8 4942.7 4966.0 4761.8 4768.4 4783.6 4821.3 4942.7 4459.0 4738.9 4741.1 4748.8 4753.1 4864.4 4870.8 4871.4 4873.1 4881.5 4791.9 4811.8 4820.7 4825.9 4843.1
22:55:52 INFO - PROCESS | 3740 |
22:55:52 INFO - PROCESS | 3740 | [#1] ext.html Cycles:180 Average:57255.22 Median:40312.10 stddev:115982.90 (287.7%) stddev-sans-first:116230.03
22:55:52 INFO - PROCESS | 3740 | Values: 517.7 538.5 548.1 551.9 578.8 37098.0 40271.4 40277.6 40346.6 40460.6 40437.8 40707.8 40980.4 41125.0 41147.4 8241.3 8284.0 8299.9 8352.2 8444.9 7996.3 8321.8 8382.7 8397.8 8401.6 8110.3 8179.5 8404.1 8418.2 8434.3 8155.8 8280.8 8391.0 8410.3 8417.6 8330.4 8350.2 8369.2 8390.0 8397.7 37981.2 38066.4 38182.0 38204.6 38243.8 2522.4 2526.2 2617.8 2796.4 2859.5 30545.4 30621.4 30855.7 30870.2 31593.4 3252.4 3290.0 3313.7 3323.0 3350.2 319300.0 333847.0 335665.0 337047.0 337728.0 51504.5 53953.0 54022.5 54023.0 54253.0 53994.5 56009.4 56216.2 58184.4 58756.0 14310.0 14488.5 15161.7 15162.5 15187.5 10265.2 10786.7 10832.6 10844.2 10895.8 17079.7 17121.0 17948.3 18044.6 18071.3 7066.4 7121.4 7147.4 7176.8 7227.2 720.8 759.7 835.4 835.7 840.7 27097.9 27359.9 27865.0 28049.0 28509.6 773.4 899.2 903.5 924.6 959.8 13999.0 14526.7 14562.9 14660.4 14788.2 512753.0 534306.0 534795.0 537549.0 540863.0 48064.0 49125.0 49210.0 49803.3 49885.0 1013.1 1021.1 1066.4 1100.3 1140.7 27049.7 28124.6 28289.0 28527.0 28737.6 41773.0 42544.8 42810.0 44166.6 44233.6 415870.0 418241.0 423243.0 424216.0 425442.0 36513.6 37916.8 40038.6 40717.4 41266.6 41288.4 41869.8 43632.0 44156.3 44545.0 36832.4 37665.8 38030.6 38347.4 38434.8 36795.8 36808.4 37491.1 37898.9 38136.4 35254.8 35325.0 35370.5 35394.6 35701.0 31980.5 32891.0 33211.0 33516.0 34158.5 24329.4 24956.6 24987.3 25062.9 25372.7
22:55:52 INFO - PROCESS | 3740 |
22:55:52 INFO - PROCESS | 3740 | [#2] jquery.html Cycles:180 Average:25969.15 Median:42372.68 stddev:65105.92 (153.7%) stddev-sans-first:65259.97
22:55:52 INFO - PROCESS | 3740 | Values: 274.9 302.5 304.7 308.8 311.0 12407.0 18260.9 18555.6 18862.8 19347.5 17456.6 17601.7 17642.5 17863.6 17916.4 5267.2 5307.5 5490.1 5495.3 5495.8 5242.3 5305.5 5417.6 5451.5 5452.3 5237.5 5369.5 5394.8 5404.0 5405.8 5198.5 5222.3 5326.6 5331.2 5379.3 4626.8 4651.5 4657.3 4669.4 4674.2 22054.4 22134.0 22451.6 22543.8 22568.7 1735.4 1784.0 1795.8 1809.2 1968.2 8007.9 14208.1 14369.3 14669.4 14814.3 1732.2 1742.3 1768.3 1798.8 1806.0 85384.6 87566.0 87613.6 87946.1 88346.1 9167.2 9318.2 9325.9 9423.6 9430.6 10659.8 11092.2 11104.3 12018.1 12035.2 7416.9 7426.1 7459.4 7534.1 7548.5 7645.9 7652.2 7676.2 7682.0 7810.0 3856.1 3865.8 3876.5 3887.4 3894.4 1568.7 1616.0 1620.1 1632.8 1646.0 809.2 809.3 827.4 873.6 891.8 17867.5 17879.9 17903.2 17980.2 18584.5 823.9 840.1 843.0 917.1 925.2 9706.0 9747.6 10168.5 10172.8 10238.5 370233.0 389578.0 390458.0 394473.0 395350.0 88102.0 89589.6 91746.0 92109.0 92955.5 811.1 823.1 824.3 828.8 828.8 16157.8 16268.4 16470.8 16718.4 16739.2 42314.0 42431.4 43493.8 44207.8 44578.8 64253.7 65811.3 66223.3 66701.0 67364.7 7111.7 7144.7 7149.0 7170.4 7179.6 8853.5 11006.3 12999.5 13132.2 13564.7 10876.8 11085.4 11146.7 11181.1 11297.4 10730.9 10832.3 10871.8 11079.8 11324.0 11397.8 11624.6 11638.7 11777.8 11844.1 6154.4 6402.2 6446.7 6494.5 6553.6 8876.5 11059.4 11208.2 11284.9 11293.1
22:55:52 INFO - PROCESS | 3740 |
22:55:52 INFO - PROCESS | 3740 | [#3] mootools.html Cycles:180 Average:4490.09 Median:5771.29 stddev:2497.07 (43.3%) stddev-sans-first:2490.67
22:55:52 INFO - PROCESS | 3740 | Values: 1047.9 1092.9 1093.2 1191.7 1406.1 6112.5 6837.4 7040.3 7048.6 7053.7 7002.5 7076.7 7121.5 7136.9 7137.7 1897.4 1914.2 1915.3 1916.8 1917.7 1874.7 1905.0 1909.9 1919.8 1955.1 1912.5 1914.0 1931.1 1931.3 1933.2 1825.5 1855.7 1862.7 1866.7 1871.2 1877.0 1882.4 1887.7 1889.1 1896.6 6827.2 6870.4 6894.7 6908.8 6975.8 3979.5 4091.1 4094.4 4103.9 4107.5 2687.4 2742.3 2743.3 2765.1 2766.5 787.9 843.9 848.4 848.6 850.0 8899.6 8931.5 8936.5 8936.8 8970.2 3349.1 3383.5 3435.2 3453.8 3453.8 7144.7 7152.3 7157.1 7157.6 7158.5 3961.2 3965.6 3998.9 4018.5 4027.3 3360.8 3472.3 3816.2 3835.5 3835.5 2761.8 2834.7 2835.3 2927.2 3008.4 893.4 896.6 900.0 905.1 911.6 765.7 772.8 781.7 785.2 809.8 4567.5 4573.5 4706.7 4758.4 4781.7 726.3 754.8 757.3 781.6 790.6 3161.7 3178.8 3180.6 3186.4 3212.0 8062.1 8351.2 8359.1 8365.7 8367.0 8114.3 8293.3 8304.2 8304.3 8327.4 6567.7 6608.6 6639.7 6640.4 6643.9 4487.1 4601.2 4612.0 4677.9 4762.5 7526.8 7779.4 7830.6 7867.0 7871.5 7959.2 8037.4 8044.1 8045.9 8049.0 6131.8 6214.8 6224.9 6227.3 6241.4 5993.3 6527.9 6804.2 6810.6 6889.8 5549.3 6094.3 6106.1 6130.4 6206.9 6074.5 6150.6 6172.7 6205.4 6206.2 5015.3 5015.5 5124.9 5183.5 5236.7 4022.6 4203.7 4224.9 4228.7 4289.6 4142.9 4183.9 4237.8 4302.8 4421.5
22:55:52 INFO - PROCESS | 3740 |
22:55:52 INFO - PROCESS | 3740 | [#4] prototype.html Cycles:180 Average:4383.24 Median:5117.23 stddev:1395.78 (27.3%) stddev-sans-first:1364.63
22:55:52 INFO - PROCESS | 3740 | Values: 241.1 242.5 247.4 248.7 250.9 4935.8 5059.3 5073.0 5117.5 5132.7 4837.1 4980.9 5017.6 5018.2 5042.2 3567.9 3711.0 3713.9 3716.5 3717.8 3563.5 3648.9 3703.5 3708.8 3710.4 3562.5 3650.2 3655.2 3658.9 3662.8 3626.4 3646.6 3649.1 3652.1 3662.5 3036.4 3146.9 3147.0 3147.6 3149.7 4853.9 4882.6 4897.1 4900.4 4916.0 4163.1 4170.0 4246.6 4266.0 4276.8 4982.9 5168.8 5201.6 5202.3 5202.9 3482.7 3486.3 3492.6 3493.7 3496.7 5410.9 5457.5 5468.9 5472.7 5475.5 3359.7 3473.9 3520.0 3562.0 3595.2 3530.6 3552.6 3630.9 3632.2 3643.5 4249.3 4252.3 4271.2 4277.0 4277.2 5244.3 5251.9 5258.2 5267.4 5272.5 1184.7 1206.2 1207.9 1228.9 1255.8 795.3 802.1 811.0 835.8 840.9 5085.2 5089.4 5103.0 5117.7 5124.5 5366.0 5371.8 5374.2 5375.0 5380.7 4991.3 5109.1 5111.2 5124.5 5132.0 2878.2 2920.5 2921.2 2922.7 2933.2 5763.3 5784.6 5808.5 5817.9 5821.4 5491.1 5610.1 5610.3 5613.8 5613.9 5635.3 5823.0 5826.7 5827.1 5833.2 5465.7 5517.5 5518.5 5521.8 5523.5 5457.7 5459.9 5464.7 5465.3 5474.5 5545.6 5629.4 5787.2 5817.6 5836.0 3309.2 3478.8 3486.6 3487.9 3488.3 5543.6 5546.9 5555.6 5562.4 5565.4 5370.0 5382.3 5387.4 5392.2 5396.8 5231.7 5352.8 5353.5 5364.8 5372.0 5175.4 5215.8 5217.6 5218.8 5226.5 5116.9 5162.6 5277.4 5362.8 5405.0 5304.2 5306.2 5311.5 5318.8 5319.2
22:55:52 INFO - PROCESS | 3740 |
22:55:52 INFO - PROCESS | 3740 | [#5] yui.html Cycles:180 Average:19834.46 Median:2603.04 stddev:29611.62 (1137.6%) stddev-sans-first:29659.49
22:55:52 INFO - PROCESS | 3740 | Values: 607.7 612.8 635.9 645.5 700.8 13393.5 13949.7 13982.5 14020.9 14044.0 12856.8 13001.7 13489.7 13523.2 13556.3 626.3 627.4 638.7 641.6 643.0 516.4 528.8 631.1 644.4 651.0 676.0 680.5 681.2 681.3 682.5 669.6 681.3 681.4 681.4 681.4 392.2 398.0 467.6 478.5 479.2 13302.0 13339.2 13340.0 13359.1 13360.4 13086.3 13178.9 13282.3 13468.7 13676.9 12242.4 12682.9 12731.0 12849.0 12854.5 3423.6 3456.8 3516.8 3530.9 4334.1 76037.6 76345.6 76402.2 77802.6 78503.6 9549.0 9943.3 10148.3 10201.4 10345.2 16506.8 16543.8 16564.7 16601.7 16617.8 7202.6 7337.1 7445.1 7519.8 7556.9 4077.7 4133.5 4172.8 4233.7 4251.8 2879.0 2975.0 2991.4 2992.1 2992.7 545.2 552.8 559.2 559.5 560.4 504.6 627.2 629.2 696.0 712.9 8849.3 9043.2 9334.3 9549.7 9947.0 532.3 644.6 650.2 684.4 715.4 4482.4 4623.2 4651.4 4738.5 4794.2 108490.5 109129.0 111883.4 111934.4 112587.4 95935.0 97076.5 97346.0 99386.0 100254.5 83384.2 83928.6 86649.0 87565.0 87596.6 8841.8 9119.7 9135.8 9299.0 9448.0 49425.8 51695.7 51793.5 51841.5 52524.9 66811.0 71709.3 74674.3 74675.6 75108.6 10617.6 10826.2 10974.5 11138.0 11152.7 12769.0 12927.9 12939.5 12963.5 13056.4 11497.7 11619.7 11751.7 12087.2 12870.5 11274.9 11314.5 11327.9 11457.2 11545.6 10420.4 10520.2 10671.0 10770.2 11341.4 10258.3 10597.1 10621.6 10757.2 10773.9 9028.2 9675.5 9885.9 9918.8 10340.5
22:55:52 INFO - PROCESS | 3740 | -------- Summary: end --------
22:55:52 INFO - PROCESS | 3740 |
22:55:53 INFO - TEST-INFO | 3740: exit 0
22:55:53 INFO - TEST-OK | dromaeo_css | took 1119454ms
22:55:53 INFO - TEST-START | kraken
22:55:53 INFO - operating with platform_type : w7_
22:55:53 INFO - Initialising browser for kraken test...
22:55:53 INFO - TEST-INFO | started process 1108 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpswsgua\profile http://localhost:49892/getInfo.html)
22:55:57 INFO - PROCESS | 1108 | __metrics Screen width/height:1600/1200
22:55:57 INFO - PROCESS | 1108 | colorDepth:24
22:55:57 INFO - PROCESS | 1108 | Browser inner width/height: 1010/674
22:55:57 INFO - PROCESS | 1108 | __metrics
22:55:58 INFO - PROCESS | 1108 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows"
22:55:58 INFO - PROCESS | 1108 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
22:55:59 INFO - PROCESS | 1108 | console.error:
22:55:59 INFO - PROCESS | 1108 | Message: Error: Connection closed before committing the transaction.
22:55:59 INFO - PROCESS | 1108 | Stack:
22:55:59 INFO - PROCESS | 1108 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:937:23
22:55:59 INFO - PROCESS | 1108 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7
22:55:59 INFO - PROCESS | 1108 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11
22:55:59 INFO - PROCESS | 1108 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7
22:55:59 INFO - PROCESS | 1108 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7
22:55:59 INFO - PROCESS | 1108 | TaskImpl_run@resource://gre/modules/Task.jsm:324:13
22:55:59 INFO - PROCESS | 1108 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9
22:55:59 INFO - PROCESS | 1108 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15
22:55:59 INFO - PROCESS | 1108 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5
22:55:59 INFO - PROCESS | 1108 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29
22:55:59 INFO - PROCESS | 1108 | Barrier/this.client.addBlocker/promise13.1%
22:56:56 INFO - TinderboxPrint: I/O read bytes / time
88,181,248 / 1,691,780
22:56:56 INFO - TinderboxPrint: I/O write bytes / time
472,898,048 / 3,314,840
22:56:56 INFO - TinderboxPrint: CPU idle
8,253.1 (86.9%)
22:56:56 INFO - TinderboxPrint: CPU user
1,207.4 (12.7%)
22:56:56 INFO - install - Wall time: 3s; CPU: 11.0%; Read bytes: 0; Write bytes: 35624448; Read time: 0; Write time: 382300
22:56:56 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 88181248; Write bytes: 400081920; Read time: 1691780; Write time: 2833330
22:56:57 INFO - Running post-run listener: _upload_blobber_files
22:56:57 INFO - Blob upload gear active.
22:56:57 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir.
22:56:57 INFO - Files from C:\slave\test\build\blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com
22:56:57 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\venv\\Scripts\\blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', 'C:\\slave\\test\\oauth.txt', '-b', 'Autoland', '-d', 'C:\\slave\\test\\build\\blobber_upload_dir', '--output-manifest', 'C:\\slave\\test\\build\\uploaded_files.json']
22:56:57 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\venv\Scripts\blobberc.py -u https://blobupload.elasticbeanstalk.com -a C:\slave\test\oauth.txt -b Autoland -d C:\slave\test\build\blobber_upload_dir --output-manifest C:\slave\test\build\uploaded_files.json
22:56:57 INFO - (blobuploader) - INFO - Open directory for files ...
22:56:57 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log ...
22:56:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
22:56:58 INFO - (blobuploader) - INFO - Uploading, attempt #1.
22:56:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded
22:56:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
22:56:58 INFO - (blobuploader) - INFO - Done attempting.
22:56:58 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log ...
22:56:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
22:56:58 INFO - (blobuploader) - INFO - Uploading, attempt #1.
22:56:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded
22:56:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
22:56:59 INFO - (blobuploader) - INFO - Done attempting.
22:56:59 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ...
22:56:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
22:56:59 INFO - (blobuploader) - INFO - Uploading, attempt #1.
22:57:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
22:57:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
22:57:08 INFO - (blobuploader) - INFO - Done attempting.
22:57:08 INFO - (blobuploader) - INFO - Iteration through files over.
22:57:08 INFO - Return code: 0
22:57:08 INFO - rmtree: C:\slave\test\build\uploaded_files.json
22:57:08 INFO - Using _rmtree_windows ...
22:57:08 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1
22:57:08 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/04511ee6e1c0f035c3b257e1b850a63e08952942a4b130e579c427ac3e0aa2c407037c6da1356daceff919136372371fb7bc14e7690322ec2408d6177b9ede90", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/412e815351a123e754213fbafa6374d09463b45e9c73e73410e450faba41e54faf10cf4930029ea6e3adcb8c37b2a112be4e78ea0e84b51fbfad4cd0f7f3380a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2f4ed460e9f7dfa25b27cb05f823ade103a506b33cb2cf19711d851eaad3bc01a25170f70b6e6cb34acefaafdb518d2e665e291d7374187426ab2055e84a3ef6"}
22:57:08 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files
22:57:08 INFO - Writing to file C:\slave\test\properties\blobber_files
22:57:08 INFO - Contents:
22:57:08 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/04511ee6e1c0f035c3b257e1b850a63e08952942a4b130e579c427ac3e0aa2c407037c6da1356daceff919136372371fb7bc14e7690322ec2408d6177b9ede90", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/412e815351a123e754213fbafa6374d09463b45e9c73e73410e450faba41e54faf10cf4930029ea6e3adcb8c37b2a112be4e78ea0e84b51fbfad4cd0f7f3380a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2f4ed460e9f7dfa25b27cb05f823ade103a506b33cb2cf19711d851eaad3bc01a25170f70b6e6cb34acefaafdb518d2e665e291d7374187426ab2055e84a3ef6"}
22:57:08 INFO - Running post-run listener: copy_logs_to_upload_dir
22:57:08 INFO - Copying logs to upload dir...
22:57:08 INFO - mkdir: C:\slave\test\build\upload\logs
22:57:08 INFO - Copying logs to upload dir...
22:57:08 INFO - Using _rmtree_windows ...
22:57:08 INFO - Using _rmtree_windows ...
22:57:08 INFO - Using _rmtree_windows ...
22:57:08 INFO - Using _rmtree_windows ...
22:57:08 INFO - Using _rmtree_windows ...
22:57:08 INFO - Using _rmtree_windows ...
22:57:08 INFO - Using _rmtree_windows ...
program finished with exit code 0
elapsedTime=1411.884000
========= master_lag: 0.10 =========
========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 31 secs) (at 2016-09-26 22:57:08.830884) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-26 22:57:08.831554) =========
'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-068
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-068
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-068
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/04511ee6e1c0f035c3b257e1b850a63e08952942a4b130e579c427ac3e0aa2c407037c6da1356daceff919136372371fb7bc14e7690322ec2408d6177b9ede90", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/412e815351a123e754213fbafa6374d09463b45e9c73e73410e450faba41e54faf10cf4930029ea6e3adcb8c37b2a112be4e78ea0e84b51fbfad4cd0f7f3380a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2f4ed460e9f7dfa25b27cb05f823ade103a506b33cb2cf19711d851eaad3bc01a25170f70b6e6cb34acefaafdb518d2e665e291d7374187426ab2055e84a3ef6"}
build_url:https://queue.taskcluster.net/v1/task/M6BxDJNQRVaKb9MGEgyr_A/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
program finished with exit code 0
elapsedTime=0.102000
build_url: 'https://queue.taskcluster.net/v1/task/M6BxDJNQRVaKb9MGEgyr_A/artifacts/public/build/firefox-52.0a1.en-US.win32.zip'
blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/04511ee6e1c0f035c3b257e1b850a63e08952942a4b130e579c427ac3e0aa2c407037c6da1356daceff919136372371fb7bc14e7690322ec2408d6177b9ede90", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/412e815351a123e754213fbafa6374d09463b45e9c73e73410e450faba41e54faf10cf4930029ea6e3adcb8c37b2a112be4e78ea0e84b51fbfad4cd0f7f3380a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2f4ed460e9f7dfa25b27cb05f823ade103a506b33cb2cf19711d851eaad3bc01a25170f70b6e6cb34acefaafdb518d2e665e291d7374187426ab2055e84a3ef6"}'
========= master_lag: 0.01 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-26 22:57:08.945882) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-26 22:57:08.946207) =========
'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-068
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-068
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-068
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-26 22:57:09.057258) =========
========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-09-26 22:57:09.057580) =========
========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-09-26 22:57:09.668699) =========
========= Total master_lag: 0.25 =========