builder: autoland_win7_ix_test-dromaeojs
slave: t-w732-ix-051
starttime: 1474562606.18
results: success (0)
buildid: 20160922080000
builduid: 81e5fea5ccfe4d0da0df2ffe9ed22e27
revision: 9c437117dafadc3283185427ebbfe40cbc99e877
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-22 09:43:26.184816) =========
master: http://buildbot-master111.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-22 09:43:26.185296) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-22 09:43:26.185575) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-22 09:43:26.337106) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-22 09:43:26.337510) =========
'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-051
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-051
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-051
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
--09:43:27-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
=> `archiver_client.py'
Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102
Connecting to hg.mozilla.org|63.245.215.25|:443... connected.
HTTP request sent, awaiting response... 200 Script output follows
Length: 12,141 (12K) [text/x-python]
0K .......... . 100% 32.92 MB/s
09:43:27 (32.92 MB/s) - `archiver_client.py' saved [12141/12141]
program finished with exit code 0
elapsedTime=1.459000
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-22 09:43:27.817088) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 15 secs) (at 2016-09-22 09:43:27.817447) =========
'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-051
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-051
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-051
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=15.301000
========= master_lag: 0.02 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 15 secs) (at 2016-09-22 09:43:43.135572) =========
========= Started 'bash -c ...' (results: 0, elapsed: 6 secs) (at 2016-09-22 09:43:43.136020) =========
'bash' '-c' u'python archiver_client.py mozharness --repo integration/autoland --rev 9c437117dafadc3283185427ebbfe40cbc99e877 --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 9c437117dafadc3283185427ebbfe40cbc99e877 --destination scripts --debug']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-051
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-051
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-051
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-22 09:43:43,974 truncating revision to first 12 chars
2016-09-22 09:43:43,974 Setting DEBUG logging.
2016-09-22 09:43:43,974 attempt 1/10
2016-09-22 09:43:43,974 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/9c437117dafa?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2016-09-22 09:43:45,196 unpacking tar archive at: autoland-9c437117dafa/testing/mozharness/
2016-09-22 09:43:45,388 skipping symlink on windows: configs/single_locale/linux32.py
program finished with exit code 0
elapsedTime=2.342000
========= master_lag: 4.38 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 6 secs) (at 2016-09-22 09:43:49.859675) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-22 09:43:49.860004) =========
script_repo_revision: 9c437117dafadc3283185427ebbfe40cbc99e877
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-22 09:43:49.860408) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-22 09:43:49.860675) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-22 09:43:49.999572) =========
========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 22 mins, 30 secs) (at 2016-09-22 09:43:50.000072) =========
'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Autoland-Non-PGO' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Autoland-Non-PGO'
in dir C:\slave\test\. (timeout 3600 secs) (maxTime 7200 secs)
watching logfiles {}
argv: ['c:/mozilla-build/python27/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland-Non-PGO', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland-Non-PGO']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-051
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-051
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-051
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
09:43:50 INFO - MultiFileLogger online at 20160922 09:43:50 in C:\slave\test
09:43:50 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO
09:43:50 INFO - Dumping config to C:\slave\test\logs\localconfig.json.
09:43:50 INFO - {'append_to_log': False,
09:43:50 INFO - 'base_work_dir': 'C:\\slave\\test',
09:43:50 INFO - 'blob_upload_branch': 'Autoland-Non-PGO',
09:43:50 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt',
09:43:50 INFO - 'branch': 'Autoland-Non-PGO',
09:43:50 INFO - 'buildbot_json_path': 'buildprops.json',
09:43:50 INFO - 'config_files': ('talos/windows_config.py',),
09:43:50 INFO - 'default_actions': ('clobber',
09:43:50 INFO - 'read-buildbot-config',
09:43:50 INFO - 'download-and-extract',
09:43:50 INFO - 'populate-webroot',
09:43:50 INFO - 'create-virtualenv',
09:43:50 INFO - 'install',
09:43:50 INFO - 'run-tests'),
09:43:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
09:43:50 INFO - 'download_minidump_stackwalk': True,
09:43:50 INFO - 'download_symbols': 'ondemand',
09:43:50 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python',
09:43:50 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'),
09:43:50 INFO - 'hg': 'c:/mozilla-build/hg/hg',
09:43:50 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python',
09:43:50 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'),
09:43:50 INFO - 'python': 'c:/mozilla-build/python27/python.exe',
09:43:50 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe',
09:43:50 INFO - 'C:/mozilla-build/tooltool.py'),
09:43:50 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe',
09:43:50 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')},
09:43:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
09:43:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
09:43:50 INFO - 'installer_path': 'installer.exe',
09:43:50 INFO - 'log_level': 'info',
09:43:50 INFO - 'log_name': 'talos',
09:43:50 INFO - 'log_to_console': True,
09:43:50 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe',
09:43:50 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe',
09:43:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest',
09:43:50 INFO - 'opt_config_files': (),
09:43:50 INFO - 'pip_index': False,
09:43:50 INFO - 'sps_profile': False,
09:43:50 INFO - 'sps_profile_interval': 0,
09:43:50 INFO - 'suite': 'dromaeojs',
09:43:50 INFO - 'system_bits': '32',
09:43:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
09:43:50 INFO - 'title': 'T-W732-IX-051',
09:43:50 INFO - 'use_talos_json': True,
09:43:50 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'),
09:43:50 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv',
09:43:50 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll',
09:43:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
09:43:50 INFO - 'work_dir': 'build'}
09:43:50 INFO - [mozharness: 2016-09-22 16:43:50.757000Z] Running clobber step.
09:43:50 INFO - Running pre-action listener: _resource_record_pre_action
09:43:50 INFO - Running main action method: clobber
09:43:50 INFO - rmtree: C:\slave\test\build
09:43:50 INFO - Using _rmtree_windows ...
09:43:50 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1
09:44:14 INFO - Running post-action listener: _resource_record_post_action
09:44:14 INFO - [mozharness: 2016-09-22 16:44:14.662000Z] Finished clobber step (success)
09:44:14 INFO - [mozharness: 2016-09-22 16:44:14.662000Z] Running read-buildbot-config step.
09:44:14 INFO - Running pre-action listener: _resource_record_pre_action
09:44:14 INFO - Running main action method: read_buildbot_config
09:44:14 INFO - Using buildbot properties:
09:44:14 INFO - {
09:44:14 INFO - "project": "",
09:44:14 INFO - "product": "firefox",
09:44:14 INFO - "script_repo_revision": "production",
09:44:14 INFO - "builddir": "autoland_win7_ix_test-dromaeojs",
09:44:14 INFO - "repository": "",
09:44:14 INFO - "buildername": "Windows 7 32-bit autoland talos dromaeojs",
09:44:14 INFO - "buildid": "20160922080000",
09:44:14 INFO - "pgo_build": "False",
09:44:14 INFO - "basedir": "C:\\slave\\test",
09:44:14 INFO - "buildnumber": 208,
09:44:14 INFO - "slavename": "t-w732-ix-051",
09:44:14 INFO - "master": "http://buildbot-master111.bb.releng.scl3.mozilla.com:8201/",
09:44:14 INFO - "platform": "win7_ix",
09:44:14 INFO - "scheduler": "tests-autoland-win32-talos",
09:44:14 INFO - "branch": "autoland",
09:44:14 INFO - "repo_path": "integration/autoland",
09:44:14 INFO - "revision": "9c437117dafadc3283185427ebbfe40cbc99e877",
09:44:14 INFO - "stage_platform": "win32",
09:44:14 INFO - "builduid": "81e5fea5ccfe4d0da0df2ffe9ed22e27",
09:44:14 INFO - "slavebuilddir": "test"
09:44:14 INFO - }
09:44:14 INFO - Found installer url https://queue.taskcluster.net/v1/task/Hj-x0xPPQmeMK9mo0JQ1KQ/artifacts/public/build/firefox-52.0a1.en-US.win32.zip.
09:44:14 INFO - Running post-action listener: _resource_record_post_action
09:44:14 INFO - [mozharness: 2016-09-22 16:44:14.666000Z] Finished read-buildbot-config step (success)
09:44:14 INFO - [mozharness: 2016-09-22 16:44:14.666000Z] Running download-and-extract step.
09:44:14 INFO - Running pre-action listener: _resource_record_pre_action
09:44:14 INFO - Running main action method: download_and_extract
09:44:14 INFO - mkdir: C:\slave\test\build\tests
09:44:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
09:44:14 INFO - trying https://queue.taskcluster.net/v1/task/Hj-x0xPPQmeMK9mo0JQ1KQ/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json
09:44:14 INFO - Downloading https://queue.taskcluster.net/v1/task/Hj-x0xPPQmeMK9mo0JQ1KQ/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json to C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json
09:44:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Hj-x0xPPQmeMK9mo0JQ1KQ/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json', 'file_name': 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.test_packages.json'}, attempt #1
09:44:15 INFO - Downloaded 1274 bytes.
09:44:15 INFO - Reading from file C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json
09:44:15 INFO - Using the following test package requirements:
09:44:15 INFO - {u'common': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
09:44:15 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
09:44:15 INFO - u'firefox-52.0a1.en-US.win32.cppunittest.tests.zip'],
09:44:15 INFO - u'gtest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
09:44:15 INFO - u'firefox-52.0a1.en-US.win32.gtest.tests.zip'],
09:44:15 INFO - u'jittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
09:44:15 INFO - u'jsshell-win32.zip'],
09:44:15 INFO - u'mochitest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
09:44:15 INFO - u'firefox-52.0a1.en-US.win32.mochitest.tests.zip'],
09:44:15 INFO - u'mozbase': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
09:44:15 INFO - u'reftest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
09:44:15 INFO - u'firefox-52.0a1.en-US.win32.reftest.tests.zip'],
09:44:15 INFO - u'talos': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
09:44:15 INFO - u'firefox-52.0a1.en-US.win32.talos.tests.zip'],
09:44:15 INFO - u'web-platform': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
09:44:15 INFO - u'firefox-52.0a1.en-US.win32.web-platform.tests.zip'],
09:44:15 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
09:44:15 INFO - u'firefox-52.0a1.en-US.win32.xpcshell.tests.zip']}
09:44:15 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip'] for test suite category: common
09:44:15 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/Hj-x0xPPQmeMK9mo0JQ1KQ/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip
09:44:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Hj-x0xPPQmeMK9mo0JQ1KQ/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1
09:44:15 INFO - Fetch https://queue.taskcluster.net/v1/task/Hj-x0xPPQmeMK9mo0JQ1KQ/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory
09:44:16 INFO - Expected file size: 17286402
09:44: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
09:44:48 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/Hj-x0xPPQmeMK9mo0JQ1KQ/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip
09:44:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Hj-x0xPPQmeMK9mo0JQ1KQ/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1
09:44:48 INFO - Fetch https://queue.taskcluster.net/v1/task/Hj-x0xPPQmeMK9mo0JQ1KQ/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory
09:44:49 INFO - Expected file size: 17286402
09:44:59 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/Hj-x0xPPQmeMK9mo0JQ1KQ/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip
09:44:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Hj-x0xPPQmeMK9mo0JQ1KQ/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip'}, attempt #1
09:44:59 INFO - Fetch https://queue.taskcluster.net/v1/task/Hj-x0xPPQmeMK9mo0JQ1KQ/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip into memory
09:45:00 INFO - Expected file size: 14414059
09:45:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
09:45:09 INFO - trying https://queue.taskcluster.net/v1/task/Hj-x0xPPQmeMK9mo0JQ1KQ/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
09:45:09 INFO - Downloading https://queue.taskcluster.net/v1/task/Hj-x0xPPQmeMK9mo0JQ1KQ/artifacts/public/build/firefox-52.0a1.en-US.win32.zip to C:\slave\test\build\firefox-52.0a1.en-US.win32.zip
09:45:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Hj-x0xPPQmeMK9mo0JQ1KQ/artifacts/public/build/firefox-52.0a1.en-US.win32.zip', 'file_name': 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.zip'}, attempt #1
09:45:12 INFO - Downloaded 53483312 bytes.
09:45:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Hj-x0xPPQmeMK9mo0JQ1KQ/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
09:45:12 INFO - mkdir: C:\slave\test\properties
09:45:12 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url
09:45:12 INFO - Writing to file C:\slave\test\properties\build_url
09:45:12 INFO - Contents:
09:45:12 INFO - build_url:https://queue.taskcluster.net/v1/task/Hj-x0xPPQmeMK9mo0JQ1KQ/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
09:45:13 INFO - Running post-action listener: _resource_record_post_action
09:45:13 INFO - Running post-action listener: set_extra_try_arguments
09:45:13 INFO - [mozharness: 2016-09-22 16:45:13.074000Z] Finished download-and-extract step (success)
09:45:13 INFO - [mozharness: 2016-09-22 16:45:13.074000Z] Running populate-webroot step.
09:45:13 INFO - Running pre-action listener: _resource_record_pre_action
09:45:13 INFO - Running main action method: populate_webroot
09:45:13 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']},
09:45:13 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']},
09:45:13 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome',
09:45:13 INFO - u'--tppagecycles',
09:45:13 INFO - u'7'],
09:45:13 INFO - u'tests': [u'tsvgm']}},
09:45:13 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'],
09:45:13 INFO - u'tests': [u'tresize', u'tcanvasmark']},
09:45:13 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']},
09:45:13 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'],
09:45:13 INFO - u'tests': [u'dromaeo_css', u'kraken']},
09:45:13 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']},
09:45:13 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
09:45:13 INFO - u'talos_options': [u'--disable-e10s'],
09:45:13 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
09:45:13 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
09:45:13 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
09:45:13 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
09:45:13 INFO - u'talos_options': [u'--disable-e10s'],
09:45:13 INFO - u'tests': [u'damp', u'tps']},
09:45:13 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
09:45:13 INFO - u'tests': [u'damp', u'tps']},
09:45:13 INFO - u'g3': {u'talos_options': [u'--disable-e10s'],
09:45:13 INFO - u'tests': [u'dromaeo_dom']},
09:45:13 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']},
09:45:13 INFO - u'g4': {u'talos_options': [u'--disable-e10s'],
09:45:13 INFO - u'tests': [u'basic_compositor_video']},
09:45:13 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']},
09:45:13 INFO - u'other': {u'talos_options': [u'--disable-e10s'],
09:45:13 INFO - u'tests': [u'a11yr',
09:45:13 INFO - u'ts_paint',
09:45:13 INFO - u'tpaint',
09:45:13 INFO - u'sessionrestore',
09:45:13 INFO - u'sessionrestore_no_auto_restore',
09:45:13 INFO - u'tabpaint']},
09:45:13 INFO - u'other-e10s': {u'tests': [u'a11yr',
09:45:13 INFO - u'ts_paint',
09:45:13 INFO - u'tpaint',
09:45:13 INFO - u'sessionrestore',
09:45:13 INFO - u'sessionrestore_no_auto_restore',
09:45:13 INFO - u'tabpaint']},
09:45:13 INFO - u'other-e10s_l64': {u'tests': [u'a11yr',
09:45:13 INFO - u'ts_paint',
09:45:13 INFO - u'tpaint',
09:45:13 INFO - u'sessionrestore',
09:45:13 INFO - u'sessionrestore_no_auto_restore',
09:45:13 INFO - u'tabpaint']},
09:45:13 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr',
09:45:13 INFO - u'ts_paint',
09:45:13 INFO - u'tpaint',
09:45:13 INFO - u'sessionrestore',
09:45:13 INFO - u'sessionrestore_no_auto_restore',
09:45:13 INFO - u'tabpaint']},
09:45:13 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'],
09:45:13 INFO - u'tests': [u'a11yr',
09:45:13 INFO - u'ts_paint',
09:45:13 INFO - u'tpaint',
09:45:13 INFO - u'sessionrestore',
09:45:13 INFO - u'sessionrestore_no_auto_restore',
09:45:13 INFO - u'tabpaint']},
09:45:13 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'],
09:45:13 INFO - u'tests': [u'a11yr',
09:45:13 INFO - u'ts_paint',
09:45:13 INFO - u'tpaint',
09:45:13 INFO - u'sessionrestore',
09:45:13 INFO - u'sessionrestore_no_auto_restore',
09:45:13 INFO - u'tabpaint']},
09:45:13 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'],
09:45:13 INFO - u'tests': [u'tsvgx',
09:45:13 INFO - u'tsvgr_opacity',
09:45:13 INFO - u'tart',
09:45:13 INFO - u'tscrollx',
09:45:13 INFO - u'cart']},
09:45:13 INFO - u'svgr-e10s': {u'tests': [u'tsvgx',
09:45:13 INFO - u'tsvgr_opacity',
09:45:13 INFO - u'tart',
09:45:13 INFO - u'tscrollx',
09:45:13 INFO - u'cart']},
09:45:13 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
09:45:13 INFO - u'talos_options': [u'--disable-e10s'],
09:45:13 INFO - u'tests': [u'tp5o']},
09:45:13 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
09:45:13 INFO - u'tests': [u'tp5o']},
09:45:13 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
09:45:13 INFO - u'talos_options': [u'--disable-e10s',
09:45:13 INFO - u'--xperf_path',
09:45:13 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
09:45:13 INFO - u'tests': [u'tp5n']},
09:45:13 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
09:45:13 INFO - u'talos_options': [u'--xperf_path',
09:45:13 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
09:45:13 INFO - u'tests': [u'tp5n']}},
09:45:13 INFO - u'talos.zip': {u'path': u'',
09:45:13 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}}
09:45:13 INFO - Running post-action listener: _resource_record_post_action
09:45:13 INFO - [mozharness: 2016-09-22 16:45:13.172000Z] Finished populate-webroot step (success)
09:45:13 INFO - [mozharness: 2016-09-22 16:45:13.172000Z] Running create-virtualenv step.
09:45:13 INFO - Running pre-action listener: _resource_record_pre_action
09:45:13 INFO - Running main action method: create_virtualenv
09:45:13 INFO - Creating virtualenv C:\slave\test\build/venv
09:45:13 INFO - mkdir: C:\slave\test\build\venv\Scripts
09:45:13 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll
09:45:13 WARNING - Can't copy c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll: [Errno 2] No such file or directory: 'c:/mozilla-build/python27/python27.dll'!
09:45:13 INFO - Running command: ['c:/mozilla-build/python27/python.exe', 'c:/mozilla-build/buildbotve/virtualenv.py', '--no-site-packages', '--distribute', 'C:\\slave\\test\\build/venv'] in C:\slave\test\build
09:45:13 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv
09:45:26 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe
09:45:31 INFO - Installing distribute......................................................................................................................................................................................done.
09:45:32 INFO - Return code: 0
09:45:32 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv
09:45:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
09:45:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
09:45:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
09:45:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
09:45:32 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 0x01890640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0193BE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0183B228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01958E90>, '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 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F08520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-051', 'USERDOMAIN': 'T-W732-IX-051', '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-051', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
09:45:32 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in C:\slave\test\build
09:45:32 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5
09:45:32 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
09:45:32 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
09:45:32 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
09:45:32 INFO - 'COMPUTERNAME': 'T-W732-IX-051',
09:45:32 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
09:45:32 INFO - 'CYGWINBASE': 'C:\\cygwin',
09:45:32 INFO - 'DCLOCATION': 'SCL3',
09:45:32 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
09:45:32 INFO - 'FP_NO_HOST_CHECK': 'NO',
09:45:32 INFO - 'HOMEDRIVE': 'C:',
09:45:32 INFO - 'HOMEPATH': '\\Users\\cltbld',
09:45:32 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
09:45:32 INFO - 'KTS_VERSION': '1.19c',
09:45:32 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
09:45:32 INFO - 'LOGONSERVER': '\\\\T-W732-IX-051',
09:45:32 INFO - 'MONDIR': 'C:\\Monitor_config\\',
09:45:32 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
09:45:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
09:45:32 INFO - 'MOZ_NO_REMOTE': '1',
09:45:32 INFO - 'NO_EM_RESTART': '1',
09:45:32 INFO - 'NUMBER_OF_PROCESSORS': '8',
09:45:32 INFO - 'OS': 'Windows_NT',
09:45:32 INFO - 'OURDRIVE': 'C:',
09:45:32 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
09:45:32 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
09:45:32 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
09:45:32 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
09:45:32 INFO - 'PROCESSOR_LEVEL': '6',
09:45:32 INFO - 'PROCESSOR_REVISION': '1e05',
09:45:32 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
09:45:32 INFO - 'PROGRAMFILES': 'C:\\Program Files',
09:45:32 INFO - 'PROMPT': '$P$G',
09:45:32 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
09:45:32 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
09:45:32 INFO - 'PUBLIC': 'C:\\Users\\Public',
09:45:32 INFO - 'PWD': 'C:\\slave\\test',
09:45:32 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
09:45:32 INFO - 'SLAVEDIR': 'C:\\slave\\',
09:45:32 INFO - 'SYSTEMDRIVE': 'C:',
09:45:32 INFO - 'SYSTEMROOT': 'C:\\windows',
09:45:32 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:45:32 INFO - 'TEST1': 'testie',
09:45:32 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:45:32 INFO - 'USERDOMAIN': 'T-W732-IX-051',
09:45:32 INFO - 'USERNAME': 'cltbld',
09:45:32 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
09:45:32 INFO - 'WINDIR': 'C:\\windows',
09:45:32 INFO - 'WINDOWS_TRACING_FLAGS': '3',
09:45:32 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
09:45:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
09:45:33 INFO - Ignoring indexes: http://pypi.python.org/simple/
09:45:33 INFO - Downloading/unpacking pip>=1.5
09:45:38 INFO - Running setup.py egg_info for package pip
09:45:38 INFO - warning: no files found matching 'pip\cacert.pem'
09:45:38 INFO - warning: no files found matching '*.html' under directory 'docs'
09:45:38 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
09:45:38 INFO - no previously-included directories found matching 'docs\_build\_sources'
09:45:38 INFO - Installing collected packages: pip
09:45:38 INFO - Found existing installation: pip 0.8.2
09:45:38 INFO - Uninstalling pip:
09:45:38 INFO - Successfully uninstalled pip
09:45:38 INFO - Running setup.py install for pip
09:45:38 INFO - warning: no files found matching 'pip\cacert.pem'
09:45:38 INFO - warning: no files found matching '*.html' under directory 'docs'
09:45:38 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
09:45:38 INFO - no previously-included directories found matching 'docs\_build\_sources'
09:45:38 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts
09:45:38 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts
09:45:38 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts
09:45:38 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts
09:45:38 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts
09:45:38 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts
09:45:38 INFO - Successfully installed pip
09:45:38 INFO - Cleaning up...
09:45:38 INFO - Return code: 0
09:45:38 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv
09:45:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
09:45:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
09:45:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
09:45:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
09:45:38 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 0x01890640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0193BE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0183B228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01958E90>, '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 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F08520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-051', 'USERDOMAIN': 'T-W732-IX-051', '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-051', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
09:45:38 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in C:\slave\test\build
09:45:38 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1
09:45:38 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
09:45:38 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
09:45:38 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
09:45:38 INFO - 'COMPUTERNAME': 'T-W732-IX-051',
09:45:38 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
09:45:38 INFO - 'CYGWINBASE': 'C:\\cygwin',
09:45:38 INFO - 'DCLOCATION': 'SCL3',
09:45:38 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
09:45:38 INFO - 'FP_NO_HOST_CHECK': 'NO',
09:45:38 INFO - 'HOMEDRIVE': 'C:',
09:45:38 INFO - 'HOMEPATH': '\\Users\\cltbld',
09:45:38 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
09:45:38 INFO - 'KTS_VERSION': '1.19c',
09:45:38 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
09:45:38 INFO - 'LOGONSERVER': '\\\\T-W732-IX-051',
09:45:38 INFO - 'MONDIR': 'C:\\Monitor_config\\',
09:45:38 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
09:45:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
09:45:38 INFO - 'MOZ_NO_REMOTE': '1',
09:45:38 INFO - 'NO_EM_RESTART': '1',
09:45:38 INFO - 'NUMBER_OF_PROCESSORS': '8',
09:45:38 INFO - 'OS': 'Windows_NT',
09:45:38 INFO - 'OURDRIVE': 'C:',
09:45:38 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
09:45:38 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
09:45:38 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
09:45:38 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
09:45:38 INFO - 'PROCESSOR_LEVEL': '6',
09:45:38 INFO - 'PROCESSOR_REVISION': '1e05',
09:45:38 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
09:45:38 INFO - 'PROGRAMFILES': 'C:\\Program Files',
09:45:38 INFO - 'PROMPT': '$P$G',
09:45:38 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
09:45:38 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
09:45:38 INFO - 'PUBLIC': 'C:\\Users\\Public',
09:45:38 INFO - 'PWD': 'C:\\slave\\test',
09:45:38 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
09:45:38 INFO - 'SLAVEDIR': 'C:\\slave\\',
09:45:38 INFO - 'SYSTEMDRIVE': 'C:',
09:45:38 INFO - 'SYSTEMROOT': 'C:\\windows',
09:45:38 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:45:38 INFO - 'TEST1': 'testie',
09:45:38 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:45:38 INFO - 'USERDOMAIN': 'T-W732-IX-051',
09:45:38 INFO - 'USERNAME': 'cltbld',
09:45:38 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
09:45:38 INFO - 'WINDIR': 'C:\\windows',
09:45:38 INFO - 'WINDOWS_TRACING_FLAGS': '3',
09:45:38 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
09:45:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
09:45:40 INFO - Ignoring indexes: https://pypi.python.org/simple/
09:45:40 INFO - Downloading/unpacking psutil>=3.1.1
09:45:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
09:45:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
09:45:41 INFO - Installing collected packages: psutil
09:45:41 INFO - Successfully installed psutil
09:45:41 INFO - Cleaning up...
09:45:41 INFO - Return code: 0
09:45:41 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv
09:45: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')]}
09:45:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
09:45: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')]}
09:45:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
09:45: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01890640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0193BE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0183B228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01958E90>, '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 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F08520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-051', 'USERDOMAIN': 'T-W732-IX-051', '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-051', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
09:45: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', 'mozsystemmonitor==0.3'] in C:\slave\test\build
09:45: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 mozsystemmonitor==0.3
09:45:41 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
09:45:41 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
09:45:41 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
09:45:41 INFO - 'COMPUTERNAME': 'T-W732-IX-051',
09:45:41 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
09:45:41 INFO - 'CYGWINBASE': 'C:\\cygwin',
09:45:41 INFO - 'DCLOCATION': 'SCL3',
09:45:41 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
09:45:41 INFO - 'FP_NO_HOST_CHECK': 'NO',
09:45:41 INFO - 'HOMEDRIVE': 'C:',
09:45:41 INFO - 'HOMEPATH': '\\Users\\cltbld',
09:45:41 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
09:45:41 INFO - 'KTS_VERSION': '1.19c',
09:45:41 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
09:45:41 INFO - 'LOGONSERVER': '\\\\T-W732-IX-051',
09:45:41 INFO - 'MONDIR': 'C:\\Monitor_config\\',
09:45:41 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
09:45:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
09:45:41 INFO - 'MOZ_NO_REMOTE': '1',
09:45:41 INFO - 'NO_EM_RESTART': '1',
09:45:41 INFO - 'NUMBER_OF_PROCESSORS': '8',
09:45:41 INFO - 'OS': 'Windows_NT',
09:45:41 INFO - 'OURDRIVE': 'C:',
09:45: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;',
09:45:41 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
09:45:41 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
09:45:41 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
09:45:41 INFO - 'PROCESSOR_LEVEL': '6',
09:45:41 INFO - 'PROCESSOR_REVISION': '1e05',
09:45:41 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
09:45:41 INFO - 'PROGRAMFILES': 'C:\\Program Files',
09:45:41 INFO - 'PROMPT': '$P$G',
09:45:41 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
09:45:41 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
09:45:41 INFO - 'PUBLIC': 'C:\\Users\\Public',
09:45:41 INFO - 'PWD': 'C:\\slave\\test',
09:45:41 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
09:45:41 INFO - 'SLAVEDIR': 'C:\\slave\\',
09:45:41 INFO - 'SYSTEMDRIVE': 'C:',
09:45:41 INFO - 'SYSTEMROOT': 'C:\\windows',
09:45:41 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:45:41 INFO - 'TEST1': 'testie',
09:45:41 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:45:41 INFO - 'USERDOMAIN': 'T-W732-IX-051',
09:45:41 INFO - 'USERNAME': 'cltbld',
09:45:41 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
09:45:41 INFO - 'WINDIR': 'C:\\windows',
09:45:41 INFO - 'WINDOWS_TRACING_FLAGS': '3',
09:45:41 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
09:45:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
09:45:42 INFO - Ignoring indexes: https://pypi.python.org/simple/
09:45:42 INFO - Downloading/unpacking mozsystemmonitor==0.3
09:45: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
09:45: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
09:45:43 INFO - Downloading mozsystemmonitor-0.3.tar.gz
09:45:43 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor
09:45:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in c:\slave\test\build\venv\lib\site-packages (from mozsystemmonitor==0.3)
09:45:43 INFO - Installing collected packages: mozsystemmonitor
09:45:43 INFO - Running setup.py install for mozsystemmonitor
09:45:43 INFO - Successfully installed mozsystemmonitor
09:45:43 INFO - Cleaning up...
09:45:43 INFO - Return code: 0
09:45:43 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv
09:45: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')]}
09:45:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
09:45: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')]}
09:45:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
09:45: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', '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 0x01890640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0193BE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0183B228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01958E90>, '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 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F08520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-051', 'USERDOMAIN': 'T-W732-IX-051', '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-051', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
09:45: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', 'jsonschema==2.5.1'] in C:\slave\test\build
09:45: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 jsonschema==2.5.1
09:45:43 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
09:45:43 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
09:45:43 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
09:45:43 INFO - 'COMPUTERNAME': 'T-W732-IX-051',
09:45:43 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
09:45:43 INFO - 'CYGWINBASE': 'C:\\cygwin',
09:45:43 INFO - 'DCLOCATION': 'SCL3',
09:45:43 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
09:45:43 INFO - 'FP_NO_HOST_CHECK': 'NO',
09:45:43 INFO - 'HOMEDRIVE': 'C:',
09:45:43 INFO - 'HOMEPATH': '\\Users\\cltbld',
09:45:43 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
09:45:43 INFO - 'KTS_VERSION': '1.19c',
09:45:43 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
09:45:43 INFO - 'LOGONSERVER': '\\\\T-W732-IX-051',
09:45:43 INFO - 'MONDIR': 'C:\\Monitor_config\\',
09:45:43 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
09:45:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
09:45:43 INFO - 'MOZ_NO_REMOTE': '1',
09:45:43 INFO - 'NO_EM_RESTART': '1',
09:45:43 INFO - 'NUMBER_OF_PROCESSORS': '8',
09:45:43 INFO - 'OS': 'Windows_NT',
09:45:43 INFO - 'OURDRIVE': 'C:',
09:45: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;',
09:45:43 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
09:45:43 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
09:45:43 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
09:45:43 INFO - 'PROCESSOR_LEVEL': '6',
09:45:43 INFO - 'PROCESSOR_REVISION': '1e05',
09:45:43 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
09:45:43 INFO - 'PROGRAMFILES': 'C:\\Program Files',
09:45:43 INFO - 'PROMPT': '$P$G',
09:45:43 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
09:45:43 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
09:45:43 INFO - 'PUBLIC': 'C:\\Users\\Public',
09:45:43 INFO - 'PWD': 'C:\\slave\\test',
09:45:43 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
09:45:43 INFO - 'SLAVEDIR': 'C:\\slave\\',
09:45:43 INFO - 'SYSTEMDRIVE': 'C:',
09:45:43 INFO - 'SYSTEMROOT': 'C:\\windows',
09:45:43 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:45:43 INFO - 'TEST1': 'testie',
09:45:43 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:45:43 INFO - 'USERDOMAIN': 'T-W732-IX-051',
09:45:43 INFO - 'USERNAME': 'cltbld',
09:45:43 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
09:45:43 INFO - 'WINDIR': 'C:\\windows',
09:45:43 INFO - 'WINDOWS_TRACING_FLAGS': '3',
09:45:43 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
09:45:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
09:45:44 INFO - Ignoring indexes: https://pypi.python.org/simple/
09:45:44 INFO - Downloading/unpacking jsonschema==2.5.1
09:45: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
09:45: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
09:45:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl
09:45:45 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1)
09:45: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
09:45: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
09:45:45 INFO - Downloading functools32-3.2.3-2.tar.gz
09:45:45 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32
09:45:45 INFO - warning: no files found matching '*.txt'
09:45:45 INFO - no previously-included directories found matching 'build'
09:45:45 INFO - no previously-included directories found matching 'dist'
09:45:45 INFO - no previously-included directories found matching '.git*'
09:45:45 INFO - Installing collected packages: jsonschema, functools32
09:45:45 INFO - Running setup.py install for functools32
09:45:45 INFO - warning: no files found matching '*.txt'
09:45:45 INFO - no previously-included directories found matching 'build'
09:45:45 INFO - no previously-included directories found matching 'dist'
09:45:45 INFO - no previously-included directories found matching '.git*'
09:45:45 INFO - Successfully installed jsonschema functools32
09:45:45 INFO - Cleaning up...
09:45:46 INFO - Return code: 0
09:45:46 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv
09:45:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
09:45:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
09:45:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
09:45:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
09:45:46 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 0x01890640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0193BE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0183B228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01958E90>, '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 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F08520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-051', 'USERDOMAIN': 'T-W732-IX-051', '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-051', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
09:45:46 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in C:\slave\test\build
09:45:46 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2
09:45:46 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
09:45:46 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
09:45:46 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
09:45:46 INFO - 'COMPUTERNAME': 'T-W732-IX-051',
09:45:46 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
09:45:46 INFO - 'CYGWINBASE': 'C:\\cygwin',
09:45:46 INFO - 'DCLOCATION': 'SCL3',
09:45:46 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
09:45:46 INFO - 'FP_NO_HOST_CHECK': 'NO',
09:45:46 INFO - 'HOMEDRIVE': 'C:',
09:45:46 INFO - 'HOMEPATH': '\\Users\\cltbld',
09:45:46 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
09:45:46 INFO - 'KTS_VERSION': '1.19c',
09:45:46 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
09:45:46 INFO - 'LOGONSERVER': '\\\\T-W732-IX-051',
09:45:46 INFO - 'MONDIR': 'C:\\Monitor_config\\',
09:45:46 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
09:45:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
09:45:46 INFO - 'MOZ_NO_REMOTE': '1',
09:45:46 INFO - 'NO_EM_RESTART': '1',
09:45:46 INFO - 'NUMBER_OF_PROCESSORS': '8',
09:45:46 INFO - 'OS': 'Windows_NT',
09:45:46 INFO - 'OURDRIVE': 'C:',
09:45:46 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
09:45:46 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
09:45:46 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
09:45:46 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
09:45:46 INFO - 'PROCESSOR_LEVEL': '6',
09:45:46 INFO - 'PROCESSOR_REVISION': '1e05',
09:45:46 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
09:45:46 INFO - 'PROGRAMFILES': 'C:\\Program Files',
09:45:46 INFO - 'PROMPT': '$P$G',
09:45:46 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
09:45:46 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
09:45:46 INFO - 'PUBLIC': 'C:\\Users\\Public',
09:45:46 INFO - 'PWD': 'C:\\slave\\test',
09:45:46 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
09:45:46 INFO - 'SLAVEDIR': 'C:\\slave\\',
09:45:46 INFO - 'SYSTEMDRIVE': 'C:',
09:45:46 INFO - 'SYSTEMROOT': 'C:\\windows',
09:45:46 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:45:46 INFO - 'TEST1': 'testie',
09:45:46 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:45:46 INFO - 'USERDOMAIN': 'T-W732-IX-051',
09:45:46 INFO - 'USERNAME': 'cltbld',
09:45:46 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
09:45:46 INFO - 'WINDIR': 'C:\\windows',
09:45:46 INFO - 'WINDOWS_TRACING_FLAGS': '3',
09:45:46 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
09:45:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
09:45:46 INFO - Ignoring indexes: https://pypi.python.org/simple/
09:45:46 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages
09:45:46 INFO - Cleaning up...
09:45:46 INFO - Return code: 0
09:45:46 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv
09:45:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
09:45:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
09:45:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
09:45:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
09:45:46 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 0x01890640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0193BE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0183B228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01958E90>, '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 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F08520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-051', 'USERDOMAIN': 'T-W732-IX-051', '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-051', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
09:45:46 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in C:\slave\test\build
09:45:46 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4
09:45:46 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
09:45:46 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
09:45:46 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
09:45:46 INFO - 'COMPUTERNAME': 'T-W732-IX-051',
09:45:46 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
09:45:46 INFO - 'CYGWINBASE': 'C:\\cygwin',
09:45:46 INFO - 'DCLOCATION': 'SCL3',
09:45:46 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
09:45:46 INFO - 'FP_NO_HOST_CHECK': 'NO',
09:45:46 INFO - 'HOMEDRIVE': 'C:',
09:45:46 INFO - 'HOMEPATH': '\\Users\\cltbld',
09:45:46 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
09:45:46 INFO - 'KTS_VERSION': '1.19c',
09:45:46 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
09:45:46 INFO - 'LOGONSERVER': '\\\\T-W732-IX-051',
09:45:46 INFO - 'MONDIR': 'C:\\Monitor_config\\',
09:45:46 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
09:45:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
09:45:46 INFO - 'MOZ_NO_REMOTE': '1',
09:45:46 INFO - 'NO_EM_RESTART': '1',
09:45:46 INFO - 'NUMBER_OF_PROCESSORS': '8',
09:45:46 INFO - 'OS': 'Windows_NT',
09:45:46 INFO - 'OURDRIVE': 'C:',
09:45:46 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
09:45:46 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
09:45:46 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
09:45:46 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
09:45:46 INFO - 'PROCESSOR_LEVEL': '6',
09:45:46 INFO - 'PROCESSOR_REVISION': '1e05',
09:45:46 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
09:45:46 INFO - 'PROGRAMFILES': 'C:\\Program Files',
09:45:46 INFO - 'PROMPT': '$P$G',
09:45:46 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
09:45:46 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
09:45:46 INFO - 'PUBLIC': 'C:\\Users\\Public',
09:45:46 INFO - 'PWD': 'C:\\slave\\test',
09:45:46 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
09:45:46 INFO - 'SLAVEDIR': 'C:\\slave\\',
09:45:46 INFO - 'SYSTEMDRIVE': 'C:',
09:45:46 INFO - 'SYSTEMROOT': 'C:\\windows',
09:45:46 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:45:46 INFO - 'TEST1': 'testie',
09:45:46 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:45:46 INFO - 'USERDOMAIN': 'T-W732-IX-051',
09:45:46 INFO - 'USERNAME': 'cltbld',
09:45:46 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
09:45:46 INFO - 'WINDIR': 'C:\\windows',
09:45:46 INFO - 'WINDOWS_TRACING_FLAGS': '3',
09:45:46 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
09:45:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
09:45:46 INFO - Ignoring indexes: https://pypi.python.org/simple/
09:45:46 INFO - Downloading/unpacking blobuploader==1.2.4
09:45: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
09:45: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
09:45:47 INFO - Downloading blobuploader-1.2.4.tar.gz
09:45:47 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader
09:45:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4)
09:45:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
09:45:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
09:45:48 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests
09:45:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4)
09:45:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
09:45:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
09:45:48 INFO - Downloading docopt-0.6.1.tar.gz
09:45:48 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt
09:45:48 INFO - Installing collected packages: blobuploader, requests, docopt
09:45:48 INFO - Running setup.py install for blobuploader
09:45:49 INFO - Running setup.py install for requests
09:45:49 INFO - Running setup.py install for docopt
09:45:50 INFO - Successfully installed blobuploader requests docopt
09:45:50 INFO - Cleaning up...
09:45:51 INFO - Return code: 0
09:45:51 INFO - Installing None into virtualenv C:\slave\test\build/venv
09:45:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
09:45:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
09:45:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
09:45:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
09:45:51 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 0x01890640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0193BE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0183B228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01958E90>, '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 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F08520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-051', 'USERDOMAIN': 'T-W732-IX-051', '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-051', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
09:45:51 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--no-deps', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test\build\tests\config
09:45:51 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --no-deps --timeout 120 -r C:\slave\test\build\tests\config\mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub
09:45:51 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
09:45:51 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
09:45:51 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
09:45:51 INFO - 'COMPUTERNAME': 'T-W732-IX-051',
09:45:51 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
09:45:51 INFO - 'CYGWINBASE': 'C:\\cygwin',
09:45:51 INFO - 'DCLOCATION': 'SCL3',
09:45:51 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
09:45:51 INFO - 'FP_NO_HOST_CHECK': 'NO',
09:45:51 INFO - 'HOMEDRIVE': 'C:',
09:45:51 INFO - 'HOMEPATH': '\\Users\\cltbld',
09:45:51 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
09:45:51 INFO - 'KTS_VERSION': '1.19c',
09:45:51 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
09:45:51 INFO - 'LOGONSERVER': '\\\\T-W732-IX-051',
09:45:51 INFO - 'MONDIR': 'C:\\Monitor_config\\',
09:45:51 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
09:45:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
09:45:51 INFO - 'MOZ_NO_REMOTE': '1',
09:45:51 INFO - 'NO_EM_RESTART': '1',
09:45:51 INFO - 'NUMBER_OF_PROCESSORS': '8',
09:45:51 INFO - 'OS': 'Windows_NT',
09:45:51 INFO - 'OURDRIVE': 'C:',
09:45:51 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
09:45:51 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
09:45:51 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
09:45:51 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
09:45:51 INFO - 'PROCESSOR_LEVEL': '6',
09:45:51 INFO - 'PROCESSOR_REVISION': '1e05',
09:45:51 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
09:45:51 INFO - 'PROGRAMFILES': 'C:\\Program Files',
09:45:51 INFO - 'PROMPT': '$P$G',
09:45:51 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
09:45:51 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
09:45:51 INFO - 'PUBLIC': 'C:\\Users\\Public',
09:45:51 INFO - 'PWD': 'C:\\slave\\test',
09:45:51 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
09:45:51 INFO - 'SLAVEDIR': 'C:\\slave\\',
09:45:51 INFO - 'SYSTEMDRIVE': 'C:',
09:45:51 INFO - 'SYSTEMROOT': 'C:\\windows',
09:45:51 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:45:51 INFO - 'TEST1': 'testie',
09:45:51 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:45:51 INFO - 'USERDOMAIN': 'T-W732-IX-051',
09:45:51 INFO - 'USERNAME': 'cltbld',
09:45:51 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
09:45:51 INFO - 'WINDIR': 'C:\\windows',
09:45:51 INFO - 'WINDOWS_TRACING_FLAGS': '3',
09:45:51 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
09:45:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
09:45:51 INFO - Ignoring indexes: https://pypi.python.org/simple/
09:45:51 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
09:45:51 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zrnoks-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
09:45:51 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
09:45:52 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gvkqdc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
09:45:52 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
09:45:52 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-iatepp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
09:45:52 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
09:45:52 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-txpldu-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
09:45:52 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
09:45:52 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dwowqn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
09:45:52 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
09:45:52 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dhrflt-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
09:45:52 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
09:45:52 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-44i5_j-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
09:45:52 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
09:45:52 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ikaugh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
09:45:52 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
09:45:52 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-sxkqn_-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
09:45:52 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
09:45:53 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-7vlsfc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
09:45:53 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
09:45:53 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-u8hob4-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
09:45:53 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
09:45:53 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qad2mn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
09:45:53 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
09:45:53 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-q2vv5r-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
09:45:53 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
09:45:53 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zkc6vo-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
09:45:53 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
09:45:53 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jriiww-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
09:45:53 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
09:45:53 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xhpn3w-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
09:45:54 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
09:45:54 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ygnedy-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
09:45:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
09:45:54 INFO - Running setup.py install for manifestparser
09:45:54 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts
09:45:54 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts
09:45:54 INFO - Running setup.py install for mozcrash
09:45:55 INFO - Running setup.py install for mozdebug
09:45:55 INFO - Running setup.py install for mozdevice
09:45:55 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts
09:45:55 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts
09:45:55 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts
09:45:55 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts
09:45:56 INFO - Running setup.py install for mozfile
09:45:56 INFO - Running setup.py install for mozhttpd
09:45:56 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts
09:45:56 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts
09:45:56 INFO - Running setup.py install for mozinfo
09:45:57 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts
09:45:57 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts
09:45:57 INFO - Running setup.py install for mozInstall
09:45:57 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts
09:45:57 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts
09:45:57 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts
09:45:57 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts
09:45:57 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts
09:45:57 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts
09:45:57 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts
09:45:57 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts
09:45:57 INFO - Running setup.py install for mozleak
09:45:57 INFO - Running setup.py install for mozlog
09:45:58 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts
09:45:58 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts
09:45:58 INFO - Running setup.py install for moznetwork
09:45:58 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts
09:45:58 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts
09:45:59 INFO - Running setup.py install for mozprocess
09:45:59 INFO - Running setup.py install for mozprofile
09:45:59 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts
09:45:59 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts
09:45:59 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts
09:45:59 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts
09:45:59 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts
09:45:59 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts
09:46:00 INFO - Running setup.py install for mozrunner
09:46:00 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts
09:46:00 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts
09:46:01 INFO - Running setup.py install for mozscreenshot
09:46:01 INFO - Running setup.py install for moztest
09:46:01 INFO - Running setup.py install for mozversion
09:46:02 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts
09:46:02 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts
09:46:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion
09:46:02 INFO - Cleaning up...
09:46:02 INFO - Return code: 0
09:46:02 INFO - Installing None into virtualenv C:\slave\test\build/venv
09:46:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
09:46:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
09:46:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
09:46:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
09:46:02 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 0x01890640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0193BE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0183B228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01958E90>, '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 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F08520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-051', 'USERDOMAIN': 'T-W732-IX-051', '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-051', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
09:46:02 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test\build\tests\config
09:46:02 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 -r C:\slave\test\build\tests\config\mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub
09:46:02 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
09:46:02 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
09:46:02 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
09:46:02 INFO - 'COMPUTERNAME': 'T-W732-IX-051',
09:46:02 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
09:46:02 INFO - 'CYGWINBASE': 'C:\\cygwin',
09:46:02 INFO - 'DCLOCATION': 'SCL3',
09:46:02 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
09:46:02 INFO - 'FP_NO_HOST_CHECK': 'NO',
09:46:02 INFO - 'HOMEDRIVE': 'C:',
09:46:02 INFO - 'HOMEPATH': '\\Users\\cltbld',
09:46:02 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
09:46:02 INFO - 'KTS_VERSION': '1.19c',
09:46:02 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
09:46:02 INFO - 'LOGONSERVER': '\\\\T-W732-IX-051',
09:46:02 INFO - 'MONDIR': 'C:\\Monitor_config\\',
09:46:02 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
09:46:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
09:46:02 INFO - 'MOZ_NO_REMOTE': '1',
09:46:02 INFO - 'NO_EM_RESTART': '1',
09:46:02 INFO - 'NUMBER_OF_PROCESSORS': '8',
09:46:02 INFO - 'OS': 'Windows_NT',
09:46:02 INFO - 'OURDRIVE': 'C:',
09:46:02 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
09:46:02 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
09:46:02 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
09:46:02 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
09:46:02 INFO - 'PROCESSOR_LEVEL': '6',
09:46:02 INFO - 'PROCESSOR_REVISION': '1e05',
09:46:02 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
09:46:02 INFO - 'PROGRAMFILES': 'C:\\Program Files',
09:46:02 INFO - 'PROMPT': '$P$G',
09:46:02 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
09:46:02 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
09:46:02 INFO - 'PUBLIC': 'C:\\Users\\Public',
09:46:02 INFO - 'PWD': 'C:\\slave\\test',
09:46:02 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
09:46:02 INFO - 'SLAVEDIR': 'C:\\slave\\',
09:46:02 INFO - 'SYSTEMDRIVE': 'C:',
09:46:02 INFO - 'SYSTEMROOT': 'C:\\windows',
09:46:02 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:46:02 INFO - 'TEST1': 'testie',
09:46:02 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:46:02 INFO - 'USERDOMAIN': 'T-W732-IX-051',
09:46:02 INFO - 'USERNAME': 'cltbld',
09:46:02 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
09:46:02 INFO - 'WINDIR': 'C:\\windows',
09:46:02 INFO - 'WINDOWS_TRACING_FLAGS': '3',
09:46:02 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
09:46:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
09:46:02 INFO - Ignoring indexes: https://pypi.python.org/simple/
09:46:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
09:46:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-nf9wzb-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
09:46:02 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///C:/slave/test/build/tests/mozbase/manifestparser in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 1))
09:46:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
09:46:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-8x40sh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
09:46:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///C:/slave/test/build/tests/mozbase/mozcrash in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2))
09:46:03 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
09:46:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zg5v6l-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
09:46:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///C:/slave/test/build/tests/mozbase/mozdebug in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 3))
09:46:03 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
09:46:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bq1ey6-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
09:46:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///C:/slave/test/build/tests/mozbase/mozdevice in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4))
09:46:03 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
09:46:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-oba4oi-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
09:46:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///C:/slave/test/build/tests/mozbase/mozfile in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 5))
09:46:03 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
09:46:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-2kyvus-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
09:46:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///C:/slave/test/build/tests/mozbase/mozhttpd in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 6))
09:46:03 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
09:46:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-nrx71x-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
09:46:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///C:/slave/test/build/tests/mozbase/mozinfo in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 7))
09:46:03 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
09:46:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kto4br-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
09:46:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///C:/slave/test/build/tests/mozbase/mozinstall in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 8))
09:46:03 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
09:46:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pnoj9o-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
09:46:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///C:/slave/test/build/tests/mozbase/mozleak in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 9))
09:46:04 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
09:46:04 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mu34go-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
09:46:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///C:/slave/test/build/tests/mozbase/mozlog in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10))
09:46:04 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
09:46:04 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-he29gw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
09:46:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///C:/slave/test/build/tests/mozbase/moznetwork in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 11))
09:46:04 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
09:46:04 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-uhqok7-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
09:46:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///C:/slave/test/build/tests/mozbase/mozprocess in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 12))
09:46:04 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
09:46:04 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qow8aq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
09:46:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///C:/slave/test/build/tests/mozbase/mozprofile in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 13))
09:46:04 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
09:46:04 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-urc1ln-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
09:46:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///C:/slave/test/build/tests/mozbase/mozrunner in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 14))
09:46:04 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
09:46:04 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qlpntn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
09:46:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///C:/slave/test/build/tests/mozbase/mozscreenshot in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 15))
09:46:04 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
09:46:04 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-2ckgsy-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
09:46:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///C:/slave/test/build/tests/mozbase/moztest in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 16))
09:46:05 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
09:46:05 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fw1bdl-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
09:46:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///C:/slave/test/build/tests/mozbase/mozversion in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 17))
09:46:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==0.17->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2))
09:46:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==0.17->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2))
09:46:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in c:\slave\test\build\venv\lib\site-packages (from mozdebug==0.1->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 3))
09:46:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.48->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4))
09:46:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.48->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4))
09:46:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10))
09:46:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
09:46:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
09:46:06 INFO - Downloading blessings-1.6.tar.gz
09:46:06 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings
09:46:06 INFO - Installing collected packages: blessings
09:46:06 INFO - Running setup.py install for blessings
09:46:07 INFO - Successfully installed blessings
09:46:07 INFO - Cleaning up...
09:46:09 INFO - Return code: 0
09:46:09 INFO - Done creating virtualenv C:\slave\test\build/venv.
09:46:09 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
09:46:09 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
09:46:10 INFO - Reading from file tmpfile_stdout
09:46:10 INFO - Using _rmtree_windows ...
09:46:10 INFO - Using _rmtree_windows ...
09:46:10 INFO - Current package versions:
09:46:10 INFO - blessings == 1.6
09:46:10 INFO - blobuploader == 1.2.4
09:46:10 INFO - docopt == 0.6.1
09:46:10 INFO - functools32 == 3.2.3-2
09:46:10 INFO - jsonschema == 2.5.1
09:46:10 INFO - manifestparser == 1.1
09:46:10 INFO - mozInstall == 1.12
09:46:10 INFO - mozcrash == 0.17
09:46:10 INFO - mozdebug == 0.1
09:46:10 INFO - mozdevice == 0.48
09:46:10 INFO - mozfile == 1.2
09:46:10 INFO - mozhttpd == 0.7
09:46:10 INFO - mozinfo == 0.9
09:46:10 INFO - mozleak == 0.1
09:46:10 INFO - mozlog == 3.4
09:46:10 INFO - moznetwork == 0.27
09:46:10 INFO - mozprocess == 0.23
09:46:10 INFO - mozprofile == 0.28
09:46:10 INFO - mozrunner == 6.12
09:46:10 INFO - mozscreenshot == 0.1
09:46:10 INFO - mozsystemmonitor == 0.3
09:46:10 INFO - moztest == 0.7
09:46:10 INFO - mozversion == 1.4
09:46:10 INFO - psutil == 3.1.1
09:46:10 INFO - requests == 1.2.3
09:46:10 INFO - Installing None into virtualenv C:\slave\test\build/venv
09:46:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
09:46:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
09:46:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
09:46:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
09:46:10 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 0x01890640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0193BE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0183B228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01958E90>, '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 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F08520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-051', 'USERDOMAIN': 'T-W732-IX-051', '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-051', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
09:46:10 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\talos\\requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test\build\tests\talos
09:46:10 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 -r C:\slave\test\build\tests\talos\requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub
09:46:10 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
09:46:10 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
09:46:10 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
09:46:10 INFO - 'COMPUTERNAME': 'T-W732-IX-051',
09:46:10 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
09:46:10 INFO - 'CYGWINBASE': 'C:\\cygwin',
09:46:10 INFO - 'DCLOCATION': 'SCL3',
09:46:10 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
09:46:10 INFO - 'FP_NO_HOST_CHECK': 'NO',
09:46:10 INFO - 'HOMEDRIVE': 'C:',
09:46:10 INFO - 'HOMEPATH': '\\Users\\cltbld',
09:46:10 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
09:46:10 INFO - 'KTS_VERSION': '1.19c',
09:46:10 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
09:46:10 INFO - 'LOGONSERVER': '\\\\T-W732-IX-051',
09:46:10 INFO - 'MONDIR': 'C:\\Monitor_config\\',
09:46:10 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
09:46:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
09:46:10 INFO - 'MOZ_NO_REMOTE': '1',
09:46:10 INFO - 'NO_EM_RESTART': '1',
09:46:10 INFO - 'NUMBER_OF_PROCESSORS': '8',
09:46:10 INFO - 'OS': 'Windows_NT',
09:46:10 INFO - 'OURDRIVE': 'C:',
09:46:10 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
09:46:10 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
09:46:10 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
09:46:10 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
09:46:10 INFO - 'PROCESSOR_LEVEL': '6',
09:46:10 INFO - 'PROCESSOR_REVISION': '1e05',
09:46:10 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
09:46:10 INFO - 'PROGRAMFILES': 'C:\\Program Files',
09:46:10 INFO - 'PROMPT': '$P$G',
09:46:10 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
09:46:10 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
09:46:10 INFO - 'PUBLIC': 'C:\\Users\\Public',
09:46:10 INFO - 'PWD': 'C:\\slave\\test',
09:46:10 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
09:46:10 INFO - 'SLAVEDIR': 'C:\\slave\\',
09:46:10 INFO - 'SYSTEMDRIVE': 'C:',
09:46:10 INFO - 'SYSTEMROOT': 'C:\\windows',
09:46:10 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:46:10 INFO - 'TEST1': 'testie',
09:46:10 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:46:10 INFO - 'USERDOMAIN': 'T-W732-IX-051',
09:46:10 INFO - 'USERNAME': 'cltbld',
09:46:10 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
09:46:10 INFO - 'WINDIR': 'C:\\windows',
09:46:10 INFO - 'WINDOWS_TRACING_FLAGS': '3',
09:46:10 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
09:46:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
09:46:10 INFO - Ignoring indexes: https://pypi.python.org/simple/
09:46:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 1))
09:46:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 2))
09:46:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 3))
09:46:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 4))
09:46:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 5))
09:46:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 6))
09:46:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 7))
09:46:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 8))
09:46:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 9))
09:46:10 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in c:\slave\test\build\venv\lib\site-packages (from mozlog>=3.1->-r C:\slave\test\build\tests\talos\requirements.txt (line 1))
09:46:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test\build\venv\lib\site-packages (from mozhttpd>=0.7->-r C:\slave\test\build\tests\talos\requirements.txt (line 4))
09:46:10 INFO - Cleaning up...
09:46:10 INFO - Return code: 0
09:46:10 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv
09:46:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
09:46:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
09:46:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
09:46:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
09:46:10 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 0x01890640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0193BE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0183B228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01958E90>, '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 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F08520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-051', 'USERDOMAIN': 'T-W732-IX-051', '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-051', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
09:46:10 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in C:\slave\test\build
09:46:10 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema
09:46:10 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
09:46:10 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
09:46:10 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
09:46:10 INFO - 'COMPUTERNAME': 'T-W732-IX-051',
09:46:10 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
09:46:10 INFO - 'CYGWINBASE': 'C:\\cygwin',
09:46:10 INFO - 'DCLOCATION': 'SCL3',
09:46:10 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
09:46:10 INFO - 'FP_NO_HOST_CHECK': 'NO',
09:46:10 INFO - 'HOMEDRIVE': 'C:',
09:46:10 INFO - 'HOMEPATH': '\\Users\\cltbld',
09:46:10 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
09:46:10 INFO - 'KTS_VERSION': '1.19c',
09:46:10 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
09:46:10 INFO - 'LOGONSERVER': '\\\\T-W732-IX-051',
09:46:10 INFO - 'MONDIR': 'C:\\Monitor_config\\',
09:46:10 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
09:46:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
09:46:10 INFO - 'MOZ_NO_REMOTE': '1',
09:46:10 INFO - 'NO_EM_RESTART': '1',
09:46:10 INFO - 'NUMBER_OF_PROCESSORS': '8',
09:46:10 INFO - 'OS': 'Windows_NT',
09:46:10 INFO - 'OURDRIVE': 'C:',
09:46:10 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
09:46:10 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
09:46:10 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
09:46:10 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
09:46:10 INFO - 'PROCESSOR_LEVEL': '6',
09:46:10 INFO - 'PROCESSOR_REVISION': '1e05',
09:46:10 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
09:46:10 INFO - 'PROGRAMFILES': 'C:\\Program Files',
09:46:10 INFO - 'PROMPT': '$P$G',
09:46:10 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
09:46:10 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
09:46:10 INFO - 'PUBLIC': 'C:\\Users\\Public',
09:46:10 INFO - 'PWD': 'C:\\slave\\test',
09:46:10 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
09:46:10 INFO - 'SLAVEDIR': 'C:\\slave\\',
09:46:10 INFO - 'SYSTEMDRIVE': 'C:',
09:46:10 INFO - 'SYSTEMROOT': 'C:\\windows',
09:46:10 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:46:10 INFO - 'TEST1': 'testie',
09:46:10 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:46:10 INFO - 'USERDOMAIN': 'T-W732-IX-051',
09:46:10 INFO - 'USERNAME': 'cltbld',
09:46:10 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
09:46:10 INFO - 'WINDIR': 'C:\\windows',
09:46:10 INFO - 'WINDOWS_TRACING_FLAGS': '3',
09:46:10 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
09:46:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
09:46:10 INFO - Ignoring indexes: https://pypi.python.org/simple/
09:46:10 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages
09:46:10 INFO - Cleaning up...
09:46:10 INFO - Return code: 0
09:46:10 INFO - Running post-action listener: _resource_record_post_action
09:46:10 INFO - Running post-action listener: _start_resource_monitoring
09:46:10 INFO - Starting resource monitoring.
09:46:10 INFO - [mozharness: 2016-09-22 16:46:10.973000Z] Finished create-virtualenv step (success)
09:46:10 INFO - [mozharness: 2016-09-22 16:46:10.973000Z] Running install step.
09:46:10 INFO - Running pre-action listener: _resource_record_pre_action
09:46:10 INFO - Running main action method: install
09:46:10 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
09:46:10 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
09:46:11 INFO - Reading from file tmpfile_stdout
09:46:11 INFO - Using _rmtree_windows ...
09:46:11 INFO - Using _rmtree_windows ...
09:46:11 INFO - Detecting whether we're running mozinstall >=1.0...
09:46:11 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h']
09:46:11 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h
09:46:11 INFO - Reading from file tmpfile_stdout
09:46:11 INFO - Output received:
09:46:11 INFO - Usage: mozinstall-script.py [options] installer
09:46:11 INFO - Options:
09:46:11 INFO - -h, --help show this help message and exit
09:46:11 INFO - -d DEST, --destination=DEST
09:46:11 INFO - Directory to install application into. [default:
09:46:11 INFO - "C:\slave\test"]
09:46:11 INFO - --app=APP Application being installed. [default: firefox]
09:46:11 INFO - Using _rmtree_windows ...
09:46:11 INFO - Using _rmtree_windows ...
09:46:11 INFO - mkdir: C:\slave\test\build\application
09:46:11 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.zip', '--destination', 'C:\\slave\\test\\build\\application']
09:46:11 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py C:\slave\test\build\firefox-52.0a1.en-US.win32.zip --destination C:\slave\test\build\application
09:46:14 INFO - Reading from file tmpfile_stdout
09:46:14 INFO - Output received:
09:46:14 INFO - C:\slave\test\build\application\firefox\firefox.exe
09:46:14 INFO - Using _rmtree_windows ...
09:46:14 INFO - Using _rmtree_windows ...
09:46:14 INFO - Running post-action listener: _resource_record_post_action
09:46:14 INFO - [mozharness: 2016-09-22 16:46:14.248000Z] Finished install step (success)
09:46:14 INFO - [mozharness: 2016-09-22 16:46:14.248000Z] Running run-tests step.
09:46:14 INFO - Running pre-action listener: _resource_record_pre_action
09:46:14 INFO - Running main action method: run_tests
09:46:14 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version']
09:46:14 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version
09:46:14 INFO - Python 2.7.3
09:46:14 INFO - Return code: 0
09:46:14 INFO - grabbing minidump binary from tooltool
09:46:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
09:46:14 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 0x01958E90>, '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 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F08520>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1
09:46:14 INFO - Running command: ['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'] in C:\slave\test\build
09:46:14 INFO - Copy/paste: c:/mozilla-build/python27/python.exe C:/mozilla-build/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file c:\builds\relengapi.tok fetch -m C:\slave\test\build\tests\config/tooltool-manifests/win32/releng.manifest -o
09:46:14 INFO - Calling ['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'] with output_timeout 600
09:46:14 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'...
09:46:14 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmpr1i6sh
09:46:14 INFO - INFO - File integrity verified, renaming tmpr1i6sh to win32-minidump_stackwalk.exe
09:46:14 INFO - Return code: 0
09:46:14 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755
09:46:14 INFO - mkdir: C:\slave\test\build\blobber_upload_dir
09:46:14 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir
09:46:14 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe
09:46:14 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir
09:46:14 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Hj-x0xPPQmeMK9mo0JQ1KQ/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-051', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs_errorsummary.log', '--log-raw=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs_raw.log'] in C:\slave\test\build
09:46:14 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --branchName Autoland-Non-PGO --suite dromaeojs --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/Hj-x0xPPQmeMK9mo0JQ1KQ/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-051 --webServer localhost --log-tbpl-level=debug --log-errorsummary=C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log --log-raw=C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log
09:46:14 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
09:46:14 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
09:46:14 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
09:46:14 INFO - 'COMPUTERNAME': 'T-W732-IX-051',
09:46:14 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
09:46:14 INFO - 'CYGWINBASE': 'C:\\cygwin',
09:46:14 INFO - 'DCLOCATION': 'SCL3',
09:46:14 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
09:46:14 INFO - 'FP_NO_HOST_CHECK': 'NO',
09:46:14 INFO - 'HOMEDRIVE': 'C:',
09:46:14 INFO - 'HOMEPATH': '\\Users\\cltbld',
09:46:14 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
09:46:14 INFO - 'KTS_VERSION': '1.19c',
09:46:14 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
09:46:14 INFO - 'LOGONSERVER': '\\\\T-W732-IX-051',
09:46:14 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir',
09:46:14 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe',
09:46:14 INFO - 'MONDIR': 'C:\\Monitor_config\\',
09:46:14 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
09:46:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
09:46:14 INFO - 'MOZ_NO_REMOTE': '1',
09:46:14 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir',
09:46:14 INFO - 'NO_EM_RESTART': '1',
09:46:14 INFO - 'NUMBER_OF_PROCESSORS': '8',
09:46:14 INFO - 'OS': 'Windows_NT',
09:46:14 INFO - 'OURDRIVE': 'C:',
09:46:14 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
09:46:14 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
09:46:14 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
09:46:14 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
09:46:14 INFO - 'PROCESSOR_LEVEL': '6',
09:46:14 INFO - 'PROCESSOR_REVISION': '1e05',
09:46:14 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
09:46:14 INFO - 'PROGRAMFILES': 'C:\\Program Files',
09:46:14 INFO - 'PROMPT': '$P$G',
09:46:14 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
09:46:14 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
09:46:14 INFO - 'PUBLIC': 'C:\\Users\\Public',
09:46:14 INFO - 'PWD': 'C:\\slave\\test',
09:46:14 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos',
09:46:14 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
09:46:14 INFO - 'SLAVEDIR': 'C:\\slave\\',
09:46:14 INFO - 'SYSTEMDRIVE': 'C:',
09:46:14 INFO - 'SYSTEMROOT': 'C:\\windows',
09:46:14 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:46:14 INFO - 'TEST1': 'testie',
09:46:14 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
09:46:14 INFO - 'USERDOMAIN': 'T-W732-IX-051',
09:46:14 INFO - 'USERNAME': 'cltbld',
09:46:14 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
09:46:14 INFO - 'WINDIR': 'C:\\windows',
09:46:14 INFO - 'WINDOWS_TRACING_FLAGS': '3',
09:46:14 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
09:46:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
09:46:14 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Hj-x0xPPQmeMK9mo0JQ1KQ/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-051', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs_errorsummary.log', '--log-raw=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs_raw.log'] with output_timeout 3600
09:46:15 INFO - mozversion application_buildid: 20160922080000
09:46:15 INFO - mozversion application_changeset: 9c437117dafadc3283185427ebbfe40cbc99e877
09:46:15 INFO - mozversion application_display_name: Nightly
09:46:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
09:46:15 INFO - mozversion application_name: Firefox
09:46:15 INFO - mozversion application_remotingname: firefox
09:46:15 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland
09:46:15 INFO - mozversion application_vendor: Mozilla
09:46:15 INFO - mozversion application_version: 52.0a1
09:46:15 INFO - mozversion platform_buildid: 20160922080000
09:46:15 INFO - mozversion platform_changeset: 9c437117dafadc3283185427ebbfe40cbc99e877
09:46:15 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland
09:46:15 INFO - mozversion platform_version: 52.0a1
09:46:15 INFO - using testdate: 1474562775
09:46:15 INFO - actual date: 1474562775
09:46:15 INFO - starting webserver on 'localhost:49543'
09:46:15 INFO - SUITE-START | Running 2 tests
09:46:15 INFO - TEST-START | dromaeo_css
09:46:15 INFO - operating with platform_type : w7_
09:46:15 INFO - Initialising browser for dromaeo_css test...
09:46:15 INFO - TEST-INFO | started process 2460 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpwjmozq\profile http://localhost:49543/getInfo.html)
09:46:23 INFO - PROCESS | 2460 | __metrics Screen width/height:1600/1200
09:46:23 INFO - PROCESS | 2460 | colorDepth:24
09:46:23 INFO - PROCESS | 2460 | Browser inner width/height: 1010/674
09:46:23 INFO - PROCESS | 2460 | __metrics
09:46:24 INFO - PROCESS | 2460 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows"
09:46:24 INFO - PROCESS | 2460 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
09:46:25 INFO - TEST-INFO | 2460: exit 0
09:46:25 INFO - Browser initialized.
09:46:25 INFO - Running cycle 1/1 for dromaeo_css test...
09:46:25 INFO - TEST-INFO | started process 3588 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpwjmozq\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1)
09:46:26 INFO - PROCESS | 3588 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0
09:46:37 INFO - PROCESS | 3588 | RSS: Main: 138969088
09:46:37 INFO - PROCESS | 3588 |
09:47:28 INFO - PROCESS | 3588 | 1474562848832 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no]
09:49:43 INFO - PROCESS | 3588 | Cycle 1(1): loaded http://localhost:49543/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49543/tests/dromaeo/cssquery-ext.html)
09:49:44 INFO - PROCESS | 3588 | RSS: Main: 171290624
09:49:44 INFO - PROCESS | 3588 |
09:52:46 INFO - PROCESS | 3588 | Cycle 1(1): loaded http://localhost:49543/tests/dromaeo/cssquery-ext.html (next: http://localhost:49543/tests/dromaeo/cssquery-jquery.html)
09:52:47 INFO - PROCESS | 3588 | RSS: Main: 205451264
09:52:47 INFO - PROCESS | 3588 |
09:55:49 INFO - PROCESS | 3588 | Cycle 1(1): loaded http://localhost:49543/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49543/tests/dromaeo/cssquery-mootools.html)
09:55:50 INFO - PROCESS | 3588 | RSS: Main: 145977344
09:55:50 INFO - PROCESS | 3588 |
09:58:52 INFO - PROCESS | 3588 | Cycle 1(1): loaded http://localhost:49543/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49543/tests/dromaeo/cssquery-prototype.html)
09:58:53 INFO - PROCESS | 3588 | RSS: Main: 136994816
09:58:53 INFO - PROCESS | 3588 |
10:01:56 INFO - PROCESS | 3588 | Cycle 1(1): loaded http://localhost:49543/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49543/tests/dromaeo/cssquery-yui.html)
10:01:56 INFO - PROCESS | 3588 | RSS: Main: 139038720
10:01:56 INFO - PROCESS | 3588 |
10:04:59 INFO - PROCESS | 3588 | Cycle 1(1): loaded http://localhost:49543/tests/dromaeo/cssquery-yui.html (next: http://localhost:49543/tests/dromaeo/cssquery-dojo.html)
10:04:59 INFO - PROCESS | 3588 | RSS: Main: 188682240
10:04:59 INFO - PROCESS | 3588 |
10:04:59 INFO - PROCESS | 3588 | __start_tp_report
10:04:59 INFO - PROCESS | 3588 | _x_x_mozilla_page_load
10:04:59 INFO - PROCESS | 3588 | _x_x_mozilla_page_load_details
10:04:59 INFO - PROCESS | 3588 | |i|pagename|runs|
10:04:59 INFO - PROCESS | 3588 | |0;dojo.html;2293.0827668932434;2301.355620426281;2303.804176644985;2329.992681433244;2365.6924599802023;3981.243563722894;3988.361862102064;4072.0837811492397;4080.2655522006103;4082.9387559186634;3831.291211125944;3908.7263891527605;3981.183857309252;4028.738132021424;4053.614906583877;3437.3812713711523;3472.131967008248;3474.0620032591123;3486.3550243204963;3486.4247399179103;3371.106656735967;3477.8087205203706;3479.19978404967;3480.2517458746365;3491.773034752746;3491.900051483787;3499.440089585667;3513;3528.6471352864637;3556.697680697131;3524.312759011994;3558.839852206657;3585.372559802024;3586.3544561978833;3587.228745819635;3394.1853955050856;3398.1844357354303;3428.177237463016;3488.6162522122545;3546.0957455848593;3811.656950874407;3839.520059992501;3896.3181443247604;3931.9606803932165;3932.6067393260732;4118.876433706993;4120.546739858613;4192.224438478891;4201.327787553977;4208.032152604918;4085.44846445729;4145.813438395249;4157.729747566398;4217.599328063829;4217.810198541073;3880.41793730937;3932.528096628423;3954.6045395460224;3963.3064213762477;4015.377616469452;4216.262154123016;4254.638355739735;4319.956800432018;4333.58830911063;4333.739975601411;4012.1574469361176;4107.383892416162;4186.392973018896;4192.056787222911;4195.035141917315;4086.387041943732;4157.043879907638;4159.521655009713;4188.267053265667;4193.161367726467;4068.2677118119022;4106.938395924063;4108.075682971367;4156.750594964312;4182.498100227931;4082.9387559186634;4101.282275601758;4101.52832424269;4117.814698338582;4149.668026557868;2934.7212014858555;2957.556366544992;2990.2225421390594;2990.925226869302;2998.190488568074;2697.473992571469;2741.040635777502;2743.3141714571357;2753.435545713124;2762.6270453488924;3816.9618303816605;3913.002184442949;3937.9015524612114;3975.6819454443576;4011.2980228460488;4087.7751723655483;4114.465119534441;4126.071633882352;4181.686373521998;4248.150369926027;3968.0675041365257;3998.220347032359;4033.8991525212105;4034.677225821927;4041.4341992121126;3553.591336996227;3554.7156227502255;3600.1719604491113;3621.8008009559726;3664.9633503664622;4189.497260328658;4265.189613973335;4309.267424537773;4315.978420107879;4397.1865204937185;4196.055887425427;4231.894202644959;4266.997255644859;4299.269124248948;4304.74171549708;4302.22559939214;4382.561743825592;4402.075564131568;4407.625351845129;4409.536998615227;4007.6793856490826;4032.6773858090696;4033.5563088059703;4111.239420707178;4210.515790683988;4183.581641835914;4196.727212731284;4237.5762423758615;4258.446401967848;4271.01766593679;4047.4333593296374;4056.3307054335705;4107.8973025674595;4110.383442483651;4134.111166099303;3933.4099885017476;4054.1486287879875;4136;4143.295639741312;4147.79261036953;3835.290471262825;3853.9807300963316;3884.5921178277003;3911.237308724885;3934.6458818706456;4014.979925100356;4020.8391664334267;4097.385392191195;4097.692673049473;4124.030852749542;3974.5429275632514;3988.3219852625703;3990.521137463523;4059.6143366380147;4059.65492933104
10:04:59 INFO - PROCESS | 3588 | |1;ext.html;522.8405336372407;548.0245163608777;553.8200084972384;563.8590352411894;573.6844735395531;25650.74349256507;36370.63629363706;37452.06369840752;37521.06197345061;40854.182916341655;37720.81139594305;37835;38147;38182;38230.235395292075;6516.0877477153235;7670.731524396661;7748.612569371537;7808.219178082175;7820.217978202163;6451.06459563363;9601.759956001086;9664.565094570726;9897.554610042565;10321.53553090109;10067.949660251688;10077.44574048427;10139.543720532556;10158.085772280494;10167.542460589255;10086.949565252164;10166.949165254164;10167.237457190666;10175.949120254352;10178.847317290247;9952.651657191964;10014.949925250326;10033.247506437046;10034.59861605535;10038.698839034842;35481.46777798335;35774.28451430957;36100.45849312263;36152.27695446103;36153.819230903675;2648.589468632365;2691.4617076584564;2708.404151086758;2725.081228382768;2819.6193513875774;26959.191224263304;26993.865030674722;27029.594556081676;27512.724872751216;27663;3287.3214713157286;3316.9004929852144;3335.4163021471145;3374.7637665363436;3397.6772206640576;210467;213527;221231;221713.89143054342;222318;46860.53139468562;46928.357507487075;47094.29358559624;47301.29048064282;47345;26365.604515931893;27024.459510809673;49630.50369496331;49661;49709;9873.950630246803;10130.392176469435;10156.593736250466;10160.745981350525;10160.898391016142;6594.241662208811;6839.931600684029;7182;7363.55818650872;7365.189829118793;13771.311434428238;13814.309284535733;13847.169169849842;15895.920520397556;16725.749113763304;6328.6202827830475;6418.903716444257;6505.154329937078;6508.8047358579315;6515.446187074057;763.1300317637897;820.1798201798201;822.116225058065;865.0311650950975;986.8618393424927;24623.3844153894;25714.871425642752;25903.870480647474;26012;26075;874.842528344904;903.4127816919055;959.6161535385902;975.0882924465648;986.1863962231188;13342.799858001943;13651.317434128254;13767.586972390844;13887.166769993833;13933.303334833217;320724;324688;325043;326007;326575;43911.56088439075;44309.11381772347;44834;44853.77573112114;44876.3268550972;965.0783501755807;985.610683779903;1051.7055224537141;1066.5253961986996;1075.0593230922943;24032.35644079115;25682.486350273262;26124.73875261223;26353.472930541666;26387.340316491474;38309.23381532298;38810.417843731804;38818.80590597029;38835.805820971844;38939;252539;252709;255377;255597;256401;21006.2647807326;21008.579828403654;21450.463738406666;21636.567268654853;22573.43566410853;37871.24257514889;38271.42592861165;38433.4234986481;38971.220575587766;39074.804625976685;31154.844225778725;32929.34141317208;33179;33584.16039599029;33736.419555839515;32141.196470088435;32480;32626.347473050882;32804.67195328016;33210.66789332076;31395.21511962128;31442;31602.525962111045;32086.51870221902;32087.679123209404;28165.859170704014;30461.847690761406;30533.389332212788;30639.387212256075;31130.844345778125;22001.779982199972;23484.765152348256;23768.762312376653;23873;23989
10:04:59 INFO - PROCESS | 3588 | |2;jquery.html;287.66918044249127;301.9064090132059;315.1396687044369;320.64408506557726;324.9983750081251;11167.162462815291;12528.686782830433;15652.84347156528;16087.11520866352;16277.83722162778;13886.861131388683;15048.021878577885;15087.09477431355;15129.09225446474;15496.612584685388;4890.999999999991;5114.1050316194705;5122.6926384416865;5131.050755610206;5132.178851383779;3491.1970246843243;3703.6851867591286;5010.498950104997;5062.063518249136;5123.846284611468;3661.1945372018113;5740.971295143519;5797.768089276424;5829.621074630157;6024.397560243984;5669.291338582677;5903.616264942765;5920.644761314292;5966.642001479925;5985.610935289193;4362.127574485084;4649.279361698942;4709.929351059737;4767.666263361566;4772.928406073912;10394.116500097502;10589.152867770637;10728.088112510446;10748.78502429947;10783.029527342576;1650.1254335202361;1678.7146185148554;1994.351835653404;2119.565489074736;2189.770074142224;8326.2506374426;8378.287845533137;8406.075331713506;8554.615042323048;8602.698905538276;1572.5282415275371;1599.8320176381544;1620.2789758557446;1639.5737108351914;1712.2808420463434;62640.37359626391;64522;64657;65989.67005164991;66991.33008669948;8582.098879617675;8637.870431943526;8728.17082377186;8969.686060987964;9068.773280667903;9595.376300540442;10093.899061009442;10241.539130739135;10952.616658416917;11812.70468238301;5827.737751801179;5832.347464945522;5874.530037596982;5888.499477544457;5989.401059894063;5523.28197334344;5637;5642.520385767174;5688.971555142198;5764.7117644117625;3508.561489789225;3553.1117220694828;3569.839357228879;3611.9344793286045;3630.727695422801;1516.385863725193;1518.9012714173543;1593.784839046737;1633.4527933142292;1644.1203955883545;737.9225181355988;753.2392283793408;766.7979472490671;852.6504133305315;894.9818160049048;15410.152441615595;15742.717305990567;15899.125548094735;16122;16334.428295009619;756.2324240895496;762.393896852004;769.4190885881123;769.992300076992;791.1495142721598;8517.23344898962;8521.53131577769;8905.910940890637;9050.818983620422;9108.952470465982;270701;289146;290103;290173;292587;65521.516557350595;70523.29476705269;73268.2673173272;74493;74556.62721686465;770.2528547309213;785.7564155111934;793.6666600028002;802.7872613757359;804.2560631416034;13725.313734313044;14134.010619256553;14237.359318830884;14586.708265834835;14679.119252844863;26789.196324110308;35037.47443788294;35273.47089793604;35354.82322588371;38313.233735325695;46798;47630;48555;48677.75661121814;49139.75430122826;6329.841753956188;6440.710168042543;6461.709223085067;6515;6579.0920633015885;9848.507574621384;9905.900940990497;10252.333598316085;10692.144628429862;10744.19418543628;8286.295664868554;8360.372972027;8472.61873215719;8741.431806932529;8843.557822108998;8810.559472026502;9031.27749780029;9057.411268267542;9112.134347237003;9251.352405331561;8716.215540601377;8960.775980600538;9134.360594758304;9137.77155571116;9244.260459163384;6740;6780.762673306411;6868.896966545406;6908.861822763617;6909.2745261746795;6757.3242675732035;8908.465492070496;9279;9495.905040949501;9585.616575336908
10:04:59 INFO - PROCESS | 3588 | |3;mootools.html;1175.6238003838769;1188.887055729706;1246.071676600932;1463.2683658170915;1469.4563011685677;6265.5927364721265;6775.695093720782;6786.72853085876;6789.830254243647;6791.219009813873;5485.122380419134;5691.658500489973;6333.619982801035;6533.575317604353;6632.734690612369;1792.1218602884558;1856.9721454178198;1866.5986812835238;1866.850651947841;1871.3637363296477;1766.2140347545349;1794.3724664176082;1813.2746901239477;1817.5819561500862;1820.9726854097132;1511.2594828533995;1570.992145039279;1572.5989872582475;1583.8495342942401;1768.7789026371704;1776.5292197567655;1832;1832.7342535332436;1838.6782313095155;1840.098351807618;1716.5880188754609;1718.149515989593;1723.6035711786235;1727.0673836128476;1728.08411541883;6527.118838956775;6551.639659818708;6592.76925307612;6621.73513059477;6622.834429139262;3680.411134218512;3776.4146557283666;3789.9621003789885;3790.2040571480024;3799.981000094982;2269.2851751698327;2359.6106642403984;2365.680633114525;2366.9171578994824;2371.5731168389684;730.0837449001522;737.0468259113339;769.9076110866732;772.3898120484811;775.0466925681443;8372.41393102483;8404.537750423786;8408.663653453854;8413;8443.240108390275;3381.6956473917467;3383.0696558446625;3383.2725963917383;3390.5083762854247;3433.8283085846106;7215.458840586977;7231.89152162718;7400.148982866932;7400.555966642019;7418.851622967511;3590.676839084495;3602.8650974942807;3604.5494313210847;3628.310620982005;3709.5548534176073;3080.8151510909415;3336.51620515024;3389.57630296213;3425.9143521412166;3427.7943323400677;2981.3336205334763;3020.8489575521135;3092.056922638617;3122.344307695364;3124.0783968728824;995.5171741705267;1002.3046052370092;1004.0511716428051;1004.2317627015193;1024.8667673202585;576.6424816613726;583.4952765857503;612.3753771153488;662.9071929929813;687.0861753867345;4007.6593489553134;4090.2228576570355;4091.9386209206887;4135.400366946776;4145.108801607669;308.8440337629483;311.88616155103676;317.62520226133387;318.3950494061302;318.74978142158295;2552.3874270175024;3149.1969547765175;3164.8892288769784;3172;3172.6510083890753;7297.854042919218;7605.353544948631;7756.767296980986;7878.251566101211;7931.484453510503;7731.420143489148;7825.608719564113;7832.295093441504;7833.020872390951;7876.645550950107;5984.16221728967;5997.640141591519;6113.358097399709;6135.294441139326;6146.4775494082605;4293.441852559147;4361.062371590124;4377.496587892306;4394.231009573376;4514;7208.52225293824;7227.349538541556;7283.562986220843;7348.3386495213545;7351.4118870489165;6941.687624057031;7297.890531641923;7489.925100749141;7497.700091996259;7501.812454688677;5410.864728381822;5644.604877658525;5687.123249327795;5687.60186786921;5697.886042279214;5838.036723940502;6137.171481850071;6189.164462797645;6191;6205.317415084427;5445.782168713207;5457.317835270591;5501.422350653123;5666.979943610025;5778.94221057784;5116.769745361393;5300.7084610345255;5304.416514183514;5375.247465354932;5398.325209348832;5047.873803154804;5093.821716239914;5160.561352285098;5198.272241886215;5198.584113271005;3563.750537462353;3576.535050443426;3613.33153366628;3623.565172179355;3656.8354424051513;3539.274448737951;3594.676479116892;3605.85576576934;3606.5672119345845;3680.907977300482
10:04:59 INFO - PROCESS | 3588 | |4;prototype.html;224.22753613800444;232.1364523970829;234.28542944020734;234.69841253988622;240.5921962273946;4214.557471465498;4361.236783562879;4370.715903466276;4403;4407.303188272518;4163.354680024601;4273.594008569181;4314.072474418;4330.826766929319;4331.675124365677;3156.611091119911;3167.0023942458165;3227.3061291822255;3228.7739858209934;3241.692039256267;3083.0750774767594;3190.7128358447735;3202.711755941965;3228.7578431617603;3229.951550726741;3054.1906394805396;3097.8624748923285;3196;3208.614966204059;3213.469777486712;3171.2864605463815;3179.3005538781545;3179.82510961897;3202.871885124592;3204.5193221016802;2814.8592570371397;2816.127000629791;2821.675507316664;2821.774258059371;2827.1659860341247;4221.261279276145;4221.915561688749;4230.196262710076;4230.851920182809;4231.344141658048;3741.5510138783247;3756.398976163828;3764.7176461765202;3772.339840527924;3774.716896232766;4184.644305234059;4404.515503294635;4444.20004399208;4444.488883778375;4452.176347375714;2994.7903646744735;3031.227037105546;3055.908322750321;3058.9541156882665;3059.816411015346;4636.304554316879;4650.441946966385;4662;4677.719336839767;4683.344331793552;3106.1147572941604;3114.1280441476424;3127.9530807037913;3128.9843550782102;3136.2943337748825;3126.124685088179;3140.701633344874;3154.6214454265178;3162.652108268089;3165.715085642303;3451.637578054268;3517.771344862576;3664.75398364552;3668.834902429397;3673.687736542424;4443.400140980991;4444.73331600105;4484.372187893698;4486.282194848808;4493.752843593634;1009.1069403577886;1070.9879164189922;1076.9553533072906;1091.3670071358592;1094.2887123369874;664.8902931016424;667.9899801502982;675.0448115915924;676.7935779587273;679.9864002719918;4225.429567008476;4326.848560300375;4340.305551111807;4350.847720329836;4358.56414358568;4651.465081515601;4659.2312268476;4668.626509879202;4679.157751604744;4703.318018887242;4327.1177037987945;4355.760433176206;4371.978140109342;4375.431193944767;4378.343248512685;2401.8397914374073;2450.3996520852147;2463.7905778008712;2466.9136580219606;2473.418746594549;4756.405449318835;4785.59322457588;4790.401199850019;4792.257200133915;4805.27920811874;4741.431028276629;4741.976290118596;4743.83396581118;4765.428148622118;4774.925641730652;4751.429828420542;4791.6885402449425;4793.113274044313;4797.832075877328;4802.975985120122;4269.146170765797;4645.2103142465185;4680.648951328596;4685.648576356719;4698.600618947427;4541.591256786905;4579.313103034571;4613.469451013042;4615.861524154281;4626.282926146453;4687.695299805639;4748.596369308648;4820.566149046603;4821.661494175392;4830.444498882533;3043.893463728759;3124.500079987191;3127.124405166557;3134.9529757053206;3135.1378370948173;4410.867673969786;4629.166749985035;4667.229907065432;4667.533246675441;4674.579287864109;4424.7123936944;4443.578055022362;4468;4502.16709908668;4502.549745025472;4462.352958821018;4504.324351347324;4515.390422292949;4518.1415531049;4523.457185137673;4389.868303950887;4400.471943366816;4407.515173330867;4435.423394958635;4437.667174961955;4364.519902810624;4522.864314070452;4532.682712210114;4537.863864084083;4547.52251013652;4445.132889286457;4461.687681862239;4464.72755264748;4471.642268618568;4484.641228701761
10:04:59 INFO - PROCESS | 3588 | |5;yui.html;565.4882331490002;576.2307319728162;582.6358525921299;584.1383958660974;640.0559175216556;12318.384080795959;13212.867871321283;13419.329033548333;13466.259355735443;13493.122947008438;12422.440990155443;12730.872691273085;12735.617931462048;12736.936315318433;12769.744605107891;518.5592246590405;523.6256076905012;527.0907684244686;638.8913884639622;644;505.84824552634063;525.4797750227285;640.7533099756581;656.1633916756126;656.9474442044625;696.4184905603827;701.4353445476389;702.56441006576;702.6065403374101;703.8908969109796;688.9586624802503;694.7464175575928;699.2832346844525;700.0968750312117;700.1912790726719;394.86179837057085;397.70172370721957;495.62827879090685;501.48597687370375;506.1900958466461;12276.570320038845;12299.631011069681;12669.556565520163;12691.873081269161;12698.682532936751;11037.944810275898;11123.165762567758;11135.387553684544;11526.942365288203;11681.649550513499;11427;11508.309501429942;11561.76876462466;11674.824877626843;11690.590829320932;3963.8414463421427;3977.3636218204947;4060.593940605945;4090.529589097262;4206.284931561642;50098.99802003976;60712.39287607111;61028;61862.69068654673;62413.68793156005;8927.464352138892;9124.315676324302;9281.30390220736;9399.57701903416;9556.856647150298;15123.924380378248;15814.130222837624;16012;16119.27463264156;16157.515274541782;6268.341824108494;6332.240131184195;6403.103565500834;6481.870362592722;6581.979793132072;3503.684668379858;3548.5919119301093;3589.784612923233;3600.7299452541165;3650;2427.320350301918;2503.3366157968153;2518.8614626195736;2529.7470252974554;2533.733957934427;434.7608815151717;435.3926272849421;441.8652311045162;445.7214241099313;446.4397181537215;497.0679975046787;497.69889217023905;515.4973900447034;515.8555604430766;516.927375695426;6331.810045698637;6360.936390636034;6543.44380727634;6582.80251592453;6895.34494223058;432.0969174425466;484.46950589104875;490.37722092941766;499.44561536694965;504.7224026785253;3117.625884893782;3154.8422578871423;3191.4095892260007;3201.535777312277;3202.759793015533;56531.71734141303;57412;64334;96470;98458.50770746244;81490.59254703808;83142.58428707818;85350;85983.57008214919;86167.56916215378;54339.72830135824;74448.62775686196;76035.23964760282;76652;77118.61440692873;6516.268290014307;6606.8017959461295;6652.101966234496;6671.766488172891;6766.898496522655;43895.34156987584;44982.55017449784;44996.32505512486;45166.77416612896;47410.762946185045;63267.367326327985;64222.67888660527;64499;65028.67485662542;65230;7900.960495197487;7903.328217101381;7941.205879412013;8041.879371809545;8242.958785206036;9099.727008189764;9324;9470.715878523655;9513.381630194015;9723.854142188015;8625.353098517762;8726.040135585208;8816.735497935073;8847.911520884967;8949.105089490999;9222.595608906011;9418.293627978066;9426.151646351862;9503.334766566273;9536.666216682383;8061.758147255592;8320.251177393822;8496.872546911925;8688.913110869064;8795.780105497413;6996.510244282851;7285.526440781544;7407.185209627044;7572.924270757222;7983.2415920487465;6987.545809522365;7145.1425828900865;7212.8918066228;7327.523710958771;7454.105507339154
10:04:59 INFO - PROCESS | 3588 | __end_tp_report
10:04:59 INFO - PROCESS | 3588 | __start_cc_report
10:04:59 INFO - PROCESS | 3588 | _x_x_mozilla_cycle_collect,825
10:04:59 INFO - PROCESS | 3588 | __end_cc_report
10:04:59 INFO - PROCESS | 3588 | __startTimestamp1474563899813__endTimestamp
10:04:59 INFO - PROCESS | 3588 |
10:04:59 INFO - PROCESS | 3588 | ------- Summary: start -------
10:04:59 INFO - PROCESS | 3588 | Number of tests: 6
10:04:59 INFO - PROCESS | 3588 |
10:04:59 INFO - PROCESS | 3588 | [#0] dojo.html Cycles:165 Average:3865.92 Median:4033.56 stddev:463.69 (11.5%) stddev-sans-first:448.39
10:04:59 INFO - PROCESS | 3588 | Values: 2293.1 2301.4 2303.8 2330.0 2365.7 3981.2 3988.4 4072.1 4080.3 4082.9 3831.3 3908.7 3981.2 4028.7 4053.6 3437.4 3472.1 3474.1 3486.4 3486.4 3371.1 3477.8 3479.2 3480.3 3491.8 3491.9 3499.4 3513.0 3528.6 3556.7 3524.3 3558.8 3585.4 3586.4 3587.2 3394.2 3398.2 3428.2 3488.6 3546.1 3811.7 3839.5 3896.3 3932.0 3932.6 4118.9 4120.5 4192.2 4201.3 4208.0 4085.4 4145.8 4157.7 4217.6 4217.8 3880.4 3932.5 3954.6 3963.3 4015.4 4216.3 4254.6 4320.0 4333.6 4333.7 4012.2 4107.4 4186.4 4192.1 4195.0 4086.4 4157.0 4159.5 4188.3 4193.2 4068.3 4106.9 4108.1 4156.8 4182.5 4082.9 4101.3 4101.5 4117.8 4149.7 2934.7 2957.6 2990.2 2990.9 2998.2 2697.5 2741.0 2743.3 2753.4 2762.6 3817.0 3913.0 3937.9 3975.7 4011.3 4087.8 4114.5 4126.1 4181.7 4248.2 3968.1 3998.2 4033.9 4034.7 4041.4 3553.6 3554.7 3600.2 3621.8 3665.0 4189.5 4265.2 4309.3 4316.0 4397.2 4196.1 4231.9 4267.0 4299.3 4304.7 4302.2 4382.6 4402.1 4407.6 4409.5 4007.7 4032.7 4033.6 4111.2 4210.5 4183.6 4196.7 4237.6 4258.4 4271.0 4047.4 4056.3 4107.9 4110.4 4134.1 3933.4 4054.1 4136.0 4143.3 4147.8 3835.3 3854.0 3884.6 3911.2 3934.6 4015.0 4020.8 4097.4 4097.7 4124.0 3974.5 3988.3 3990.5 4059.6 4059.7
10:04:59 INFO - PROCESS | 3588 |
10:04:59 INFO - PROCESS | 3588 | [#1] ext.html Cycles:180 Average:40987.25 Median:325525.00 stddev:70585.55 (21.7%) stddev-sans-first:70718.17
10:04:59 INFO - PROCESS | 3588 | Values: 522.8 548.0 553.8 563.9 573.7 25650.7 36370.6 37452.1 37521.1 40854.2 37720.8 37835.0 38147.0 38182.0 38230.2 6516.1 7670.7 7748.6 7808.2 7820.2 6451.1 9601.8 9664.6 9897.6 10321.5 10067.9 10077.4 10139.5 10158.1 10167.5 10086.9 10166.9 10167.2 10175.9 10178.8 9952.7 10014.9 10033.2 10034.6 10038.7 35481.5 35774.3 36100.5 36152.3 36153.8 2648.6 2691.5 2708.4 2725.1 2819.6 26959.2 26993.9 27029.6 27512.7 27663.0 3287.3 3316.9 3335.4 3374.8 3397.7 210467.0 213527.0 221231.0 221713.9 222318.0 46860.5 46928.4 47094.3 47301.3 47345.0 26365.6 27024.5 49630.5 49661.0 49709.0 9874.0 10130.4 10156.6 10160.7 10160.9 6594.2 6839.9 7182.0 7363.6 7365.2 13771.3 13814.3 13847.2 15895.9 16725.7 6328.6 6418.9 6505.2 6508.8 6515.4 763.1 820.2 822.1 865.0 986.9 24623.4 25714.9 25903.9 26012.0 26075.0 874.8 903.4 959.6 975.1 986.2 13342.8 13651.3 13767.6 13887.2 13933.3 320724.0 324688.0 325043.0 326007.0 326575.0 43911.6 44309.1 44834.0 44853.8 44876.3 965.1 985.6 1051.7 1066.5 1075.1 24032.4 25682.5 26124.7 26353.5 26387.3 38309.2 38810.4 38818.8 38835.8 38939.0 252539.0 252709.0 255377.0 255597.0 256401.0 21006.3 21008.6 21450.5 21636.6 22573.4 37871.2 38271.4 38433.4 38971.2 39074.8 31154.8 32929.3 33179.0 33584.2 33736.4 32141.2 32480.0 32626.3 32804.7 33210.7 31395.2 31442.0 31602.5 32086.5 32087.7 28165.9 30461.8 30533.4 30639.4 31130.8 22001.8 23484.8 23768.8 23873.0 23989.0
10:04:59 INFO - PROCESS | 3588 |
10:04:59 INFO - PROCESS | 3588 | [#2] jquery.html Cycles:180 Average:19990.38 Median:5655.91 stddev:48072.09 (849.9%) stddev-sans-first:48184.19
10:04:59 INFO - PROCESS | 3588 | Values: 287.7 301.9 315.1 320.6 325.0 11167.2 12528.7 15652.8 16087.1 16277.8 13886.9 15048.0 15087.1 15129.1 15496.6 4891.0 5114.1 5122.7 5131.1 5132.2 3491.2 3703.7 5010.5 5062.1 5123.8 3661.2 5741.0 5797.8 5829.6 6024.4 5669.3 5903.6 5920.6 5966.6 5985.6 4362.1 4649.3 4709.9 4767.7 4772.9 10394.1 10589.2 10728.1 10748.8 10783.0 1650.1 1678.7 1994.4 2119.6 2189.8 8326.3 8378.3 8406.1 8554.6 8602.7 1572.5 1599.8 1620.3 1639.6 1712.3 62640.4 64522.0 64657.0 65989.7 66991.3 8582.1 8637.9 8728.2 8969.7 9068.8 9595.4 10093.9 10241.5 10952.6 11812.7 5827.7 5832.3 5874.5 5888.5 5989.4 5523.3 5637.0 5642.5 5689.0 5764.7 3508.6 3553.1 3569.8 3611.9 3630.7 1516.4 1518.9 1593.8 1633.5 1644.1 737.9 753.2 766.8 852.7 895.0 15410.2 15742.7 15899.1 16122.0 16334.4 756.2 762.4 769.4 770.0 791.1 8517.2 8521.5 8905.9 9050.8 9109.0 270701.0 289146.0 290103.0 290173.0 292587.0 65521.5 70523.3 73268.3 74493.0 74556.6 770.3 785.8 793.7 802.8 804.3 13725.3 14134.0 14237.4 14586.7 14679.1 26789.2 35037.5 35273.5 35354.8 38313.2 46798.0 47630.0 48555.0 48677.8 49139.8 6329.8 6440.7 6461.7 6515.0 6579.1 9848.5 9905.9 10252.3 10692.1 10744.2 8286.3 8360.4 8472.6 8741.4 8843.6 8810.6 9031.3 9057.4 9112.1 9251.4 8716.2 8960.8 9134.4 9137.8 9244.3 6740.0 6780.8 6868.9 6908.9 6909.3 6757.3 8908.5 9279.0 9495.9 9585.6
10:04:59 INFO - PROCESS | 3588 |
10:04:59 INFO - PROCESS | 3588 | [#3] mootools.html Cycles:180 Average:4172.03 Median:4369.28 stddev:2353.88 (53.9%) stddev-sans-first:2349.71
10:04:59 INFO - PROCESS | 3588 | Values: 1175.6 1188.9 1246.1 1463.3 1469.5 6265.6 6775.7 6786.7 6789.8 6791.2 5485.1 5691.7 6333.6 6533.6 6632.7 1792.1 1857.0 1866.6 1866.9 1871.4 1766.2 1794.4 1813.3 1817.6 1821.0 1511.3 1571.0 1572.6 1583.8 1768.8 1776.5 1832.0 1832.7 1838.7 1840.1 1716.6 1718.1 1723.6 1727.1 1728.1 6527.1 6551.6 6592.8 6621.7 6622.8 3680.4 3776.4 3790.0 3790.2 3800.0 2269.3 2359.6 2365.7 2366.9 2371.6 730.1 737.0 769.9 772.4 775.0 8372.4 8404.5 8408.7 8413.0 8443.2 3381.7 3383.1 3383.3 3390.5 3433.8 7215.5 7231.9 7400.1 7400.6 7418.9 3590.7 3602.9 3604.5 3628.3 3709.6 3080.8 3336.5 3389.6 3425.9 3427.8 2981.3 3020.8 3092.1 3122.3 3124.1 995.5 1002.3 1004.1 1004.2 1024.9 576.6 583.5 612.4 662.9 687.1 4007.7 4090.2 4091.9 4135.4 4145.1 308.8 311.9 317.6 318.4 318.7 2552.4 3149.2 3164.9 3172.0 3172.7 7297.9 7605.4 7756.8 7878.3 7931.5 7731.4 7825.6 7832.3 7833.0 7876.6 5984.2 5997.6 6113.4 6135.3 6146.5 4293.4 4361.1 4377.5 4394.2 4514.0 7208.5 7227.3 7283.6 7348.3 7351.4 6941.7 7297.9 7489.9 7497.7 7501.8 5410.9 5644.6 5687.1 5687.6 5697.9 5838.0 6137.2 6189.2 6191.0 6205.3 5445.8 5457.3 5501.4 5667.0 5778.9 5116.8 5300.7 5304.4 5375.2 5398.3 5047.9 5093.8 5160.6 5198.3 5198.6 3563.8 3576.5 3613.3 3623.6 3656.8 3539.3 3594.7 3605.9 3606.6 3680.9
10:04:59 INFO - PROCESS | 3588 |
10:04:59 INFO - PROCESS | 3588 | [#4] prototype.html Cycles:180 Average:3743.33 Median:4362.88 stddev:1155.68 (26.5%) stddev-sans-first:1128.34
10:04:59 INFO - PROCESS | 3588 | Values: 224.2 232.1 234.3 234.7 240.6 4214.6 4361.2 4370.7 4403.0 4407.3 4163.4 4273.6 4314.1 4330.8 4331.7 3156.6 3167.0 3227.3 3228.8 3241.7 3083.1 3190.7 3202.7 3228.8 3230.0 3054.2 3097.9 3196.0 3208.6 3213.5 3171.3 3179.3 3179.8 3202.9 3204.5 2814.9 2816.1 2821.7 2821.8 2827.2 4221.3 4221.9 4230.2 4230.9 4231.3 3741.6 3756.4 3764.7 3772.3 3774.7 4184.6 4404.5 4444.2 4444.5 4452.2 2994.8 3031.2 3055.9 3059.0 3059.8 4636.3 4650.4 4662.0 4677.7 4683.3 3106.1 3114.1 3128.0 3129.0 3136.3 3126.1 3140.7 3154.6 3162.7 3165.7 3451.6 3517.8 3664.8 3668.8 3673.7 4443.4 4444.7 4484.4 4486.3 4493.8 1009.1 1071.0 1077.0 1091.4 1094.3 664.9 668.0 675.0 676.8 680.0 4225.4 4326.8 4340.3 4350.8 4358.6 4651.5 4659.2 4668.6 4679.2 4703.3 4327.1 4355.8 4372.0 4375.4 4378.3 2401.8 2450.4 2463.8 2466.9 2473.4 4756.4 4785.6 4790.4 4792.3 4805.3 4741.4 4742.0 4743.8 4765.4 4774.9 4751.4 4791.7 4793.1 4797.8 4803.0 4269.1 4645.2 4680.6 4685.6 4698.6 4541.6 4579.3 4613.5 4615.9 4626.3 4687.7 4748.6 4820.6 4821.7 4830.4 3043.9 3124.5 3127.1 3135.0 3135.1 4410.9 4629.2 4667.2 4667.5 4674.6 4424.7 4443.6 4468.0 4502.2 4502.5 4462.4 4504.3 4515.4 4518.1 4523.5 4389.9 4400.5 4407.5 4435.4 4437.7 4364.5 4522.9 4532.7 4537.9 4547.5 4445.1 4461.7 4464.7 4471.6 4484.6
10:04:59 INFO - PROCESS | 3588 |
10:04:59 INFO - PROCESS | 3588 | [#5] yui.html Cycles:180 Average:16190.80 Median:62138.19 stddev:23836.90 (38.4%) stddev-sans-first:23874.89
10:04:59 INFO - PROCESS | 3588 | Values: 565.5 576.2 582.6 584.1 640.1 12318.4 13212.9 13419.3 13466.3 13493.1 12422.4 12730.9 12735.6 12736.9 12769.7 518.6 523.6 527.1 638.9 644.0 505.8 525.5 640.8 656.2 656.9 696.4 701.4 702.6 702.6 703.9 689.0 694.7 699.3 700.1 700.2 394.9 397.7 495.6 501.5 506.2 12276.6 12299.6 12669.6 12691.9 12698.7 11037.9 11123.2 11135.4 11526.9 11681.6 11427.0 11508.3 11561.8 11674.8 11690.6 3963.8 3977.4 4060.6 4090.5 4206.3 50099.0 60712.4 61028.0 61862.7 62413.7 8927.5 9124.3 9281.3 9399.6 9556.9 15123.9 15814.1 16012.0 16119.3 16157.5 6268.3 6332.2 6403.1 6481.9 6582.0 3503.7 3548.6 3589.8 3600.7 3650.0 2427.3 2503.3 2518.9 2529.7 2533.7 434.8 435.4 441.9 445.7 446.4 497.1 497.7 515.5 515.9 516.9 6331.8 6360.9 6543.4 6582.8 6895.3 432.1 484.5 490.4 499.4 504.7 3117.6 3154.8 3191.4 3201.5 3202.8 56531.7 57412.0 64334.0 96470.0 98458.5 81490.6 83142.6 85350.0 85983.6 86167.6 54339.7 74448.6 76035.2 76652.0 77118.6 6516.3 6606.8 6652.1 6671.8 6766.9 43895.3 44982.6 44996.3 45166.8 47410.8 63267.4 64222.7 64499.0 65028.7 65230.0 7901.0 7903.3 7941.2 8041.9 8243.0 9099.7 9324.0 9470.7 9513.4 9723.9 8625.4 8726.0 8816.7 8847.9 8949.1 9222.6 9418.3 9426.2 9503.3 9536.7 8061.8 8320.3 8496.9 8688.9 8795.8 6996.5 7285.5 7407.2 7572.9 7983.2 6987.5 7145.1 7212.9 7327.5 7454.1
10:04:59 INFO - PROCESS | 3588 | -------- Summary: end --------
10:04:59 INFO - PROCESS | 3588 |
10:05:00 INFO - TEST-INFO | 3588: exit 0
10:05:01 INFO - TEST-OK | dromaeo_css | took 1125328ms
10:05:01 INFO - TEST-START | kraken
10:05:01 INFO - operating with platform_type : w7_
10:05:01 INFO - Initialising browser for kraken test...
10:05:01 INFO - TEST-INFO | started process 1484 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpu1xuj2\profile http://localhost:49543/getInfo.html)
10:05:05 INFO - PROCESS | 1484 | __metrics Screen width/height:1600/1200
10:05:05 INFO - PROCESS | 1484 | colorDepth:24
10:05:05 INFO - PROCESS | 1484 | Browser inner width/height: 1010/674
10:05:05 INFO - PROCESS | 1484 | __metrics
10:05:06 INFO - PROCESS | 1484 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows"
10:05:06 INFO - PROCESS | 1484 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
10:05:07 INFO - PROCESS | 1484 | console.error:
10:05:07 INFO - PROCESS | 1484 | Message: Error: Connection closed before committing the transaction.
10:05:07 INFO - PROCESS | 1484 | Stack:
10:05:07 INFO - PROCESS | 1484 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:937:23
10:05:07 INFO - PROCESS | 1484 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7
10:05:07 INFO - PROCESS | 1484 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11
10:05:07 INFO - PROCESS | 1484 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7
10:05:07 INFO - PROCESS | 1484 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7
10:05:07 INFO - PROCESS | 1484 | TaskImpl_run@resource://gre/modules/Task.jsm:324:13
10:05:07 INFO - PROCESS | 1484 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9
10:05:07 INFO - PROCESS | 1484 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15
10:05:07 INFO - PROCESS | 1484 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5
10:05:07 INFO - PROCESS | 1484 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29
10:05:07 INFO - PROCESS | 1484 | Barrier/this.client.addBlocker/promise13.1%
10:06:07 INFO - TinderboxPrint: I/O read bytes / time
187,723,264 / 2,606,690
10:06:07 INFO - TinderboxPrint: I/O write bytes / time
461,706,752 / 4,191,510
10:06:07 INFO - TinderboxPrint: CPU idle
8,312.1 (86.9%)
10:06:07 INFO - TinderboxPrint: CPU user
1,220.1 (12.7%)
10:06:07 INFO - install - Wall time: 3s; CPU: 8.0%; Read bytes: 32768; Write bytes: 74155008; Read time: 980; Write time: 734010
10:06:07 INFO - run-tests - Wall time: 1193s; CPU: 13.0%; Read bytes: 187568128; Write bytes: 377728000; Read time: 2574040; Write time: 3445530
10:06:07 INFO - Running post-run listener: _upload_blobber_files
10:06:07 INFO - Blob upload gear active.
10:06:07 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir.
10:06:07 INFO - Files from C:\slave\test\build\blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com
10:06:07 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\venv\\Scripts\\blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', 'C:\\slave\\test\\oauth.txt', '-b', 'Autoland-Non-PGO', '-d', 'C:\\slave\\test\\build\\blobber_upload_dir', '--output-manifest', 'C:\\slave\\test\\build\\uploaded_files.json']
10:06:07 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\venv\Scripts\blobberc.py -u https://blobupload.elasticbeanstalk.com -a C:\slave\test\oauth.txt -b Autoland-Non-PGO -d C:\slave\test\build\blobber_upload_dir --output-manifest C:\slave\test\build\uploaded_files.json
10:06:08 INFO - (blobuploader) - INFO - Open directory for files ...
10:06:08 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log ...
10:06:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
10:06:08 INFO - (blobuploader) - INFO - Uploading, attempt #1.
10:06:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded
10:06:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
10:06:09 INFO - (blobuploader) - INFO - Done attempting.
10:06:09 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log ...
10:06:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
10:06:09 INFO - (blobuploader) - INFO - Uploading, attempt #1.
10:06:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded
10:06:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
10:06:10 INFO - (blobuploader) - INFO - Done attempting.
10:06:10 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ...
10:06:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
10:06:10 INFO - (blobuploader) - INFO - Uploading, attempt #1.
10:06:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
10:06:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
10:06:17 INFO - (blobuploader) - INFO - Done attempting.
10:06:17 INFO - (blobuploader) - INFO - Iteration through files over.
10:06:17 INFO - Return code: 0
10:06:17 INFO - rmtree: C:\slave\test\build\uploaded_files.json
10:06:17 INFO - Using _rmtree_windows ...
10:06:17 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1
10:06:17 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/2d328270eb236b575a8c460baa45090a578d91b2405332d6d90c2f3d0a82a37291168d1282ff878f83b88919ba219974f44b52ce81b592db70e606a53e8498ae", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/e27f179994006056deb175d82c3afb79d7f69744f0f183b6c7289c79e53221bc66d65cfd5e06ade7501db9f8e84e7f12060a559e74f99299cb01fd40249864de", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/746163e2b533a66c369def37efee56005759e9d8c53a152c93ffd776ef1228776adcf424f3b3c80a691bd2fb4a69d9d95f9cd11246c2c6e026bc642a69b2c88f"}
10:06:17 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files
10:06:17 INFO - Writing to file C:\slave\test\properties\blobber_files
10:06:17 INFO - Contents:
10:06:17 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/2d328270eb236b575a8c460baa45090a578d91b2405332d6d90c2f3d0a82a37291168d1282ff878f83b88919ba219974f44b52ce81b592db70e606a53e8498ae", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/e27f179994006056deb175d82c3afb79d7f69744f0f183b6c7289c79e53221bc66d65cfd5e06ade7501db9f8e84e7f12060a559e74f99299cb01fd40249864de", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/746163e2b533a66c369def37efee56005759e9d8c53a152c93ffd776ef1228776adcf424f3b3c80a691bd2fb4a69d9d95f9cd11246c2c6e026bc642a69b2c88f"}
10:06:17 INFO - Running post-run listener: copy_logs_to_upload_dir
10:06:17 INFO - Copying logs to upload dir...
10:06:17 INFO - mkdir: C:\slave\test\build\upload\logs
10:06:17 INFO - Copying logs to upload dir...
10:06:17 INFO - Using _rmtree_windows ...
10:06:17 INFO - Using _rmtree_windows ...
10:06:17 INFO - Using _rmtree_windows ...
10:06:17 INFO - Using _rmtree_windows ...
10:06:17 INFO - Using _rmtree_windows ...
10:06:17 INFO - Using _rmtree_windows ...
10:06:17 INFO - Using _rmtree_windows ...
program finished with exit code 0
elapsedTime=1347.839000
========= master_lag: 2.33 =========
========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 22 mins, 30 secs) (at 2016-09-22 10:06:20.172679) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-22 10:06:20.173587) =========
'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-051
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-051
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-051
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/2d328270eb236b575a8c460baa45090a578d91b2405332d6d90c2f3d0a82a37291168d1282ff878f83b88919ba219974f44b52ce81b592db70e606a53e8498ae", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/e27f179994006056deb175d82c3afb79d7f69744f0f183b6c7289c79e53221bc66d65cfd5e06ade7501db9f8e84e7f12060a559e74f99299cb01fd40249864de", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/746163e2b533a66c369def37efee56005759e9d8c53a152c93ffd776ef1228776adcf424f3b3c80a691bd2fb4a69d9d95f9cd11246c2c6e026bc642a69b2c88f"}
build_url:https://queue.taskcluster.net/v1/task/Hj-x0xPPQmeMK9mo0JQ1KQ/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/Hj-x0xPPQmeMK9mo0JQ1KQ/artifacts/public/build/firefox-52.0a1.en-US.win32.zip'
blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/2d328270eb236b575a8c460baa45090a578d91b2405332d6d90c2f3d0a82a37291168d1282ff878f83b88919ba219974f44b52ce81b592db70e606a53e8498ae", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/e27f179994006056deb175d82c3afb79d7f69744f0f183b6c7289c79e53221bc66d65cfd5e06ade7501db9f8e84e7f12060a559e74f99299cb01fd40249864de", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/746163e2b533a66c369def37efee56005759e9d8c53a152c93ffd776ef1228776adcf424f3b3c80a691bd2fb4a69d9d95f9cd11246c2c6e026bc642a69b2c88f"}'
========= master_lag: 0.03 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-22 10:06:20.303129) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-22 10:06:20.303452) =========
'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-051
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-051
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-051
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-22 10:06:20.414367) =========
========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-09-22 10:06:20.414680) =========
========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-09-22 10:06:20.896337) =========
========= Total master_lag: 6.79 =========