builder: autoland_win7_ix_test-dromaeojs-pgo
slave: t-w732-ix-018
starttime: 1476333478.06
results: success (0)
buildid: 20161012213756
builduid: 991b1904079343db819c49ccbc6a6180
revision: 86499a089946f1f24b1e81a88623749cda6dc5ce
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-12 21:37:58.059133) =========
master: http://buildbot-master127.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-12 21:37:58.059564) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-12 21:37:58.059832) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-12 21:37:58.149645) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-12 21:37:58.149908) =========
'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-018
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-018
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-018
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
--21:37:59-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
=> `archiver_client.py'
Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25
Connecting to hg.mozilla.org|63.245.215.102|:443... connected.
WARNING: Certificate verification error for hg.mozilla.org: certificate signature failure
HTTP request sent, awaiting response... 200 Script output follows
Length: 12,179 (12K) [text/x-python]
0K .......... . 100% 16.00 MB/s
21:37:59 (16.00 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=1.219000
========= master_lag: 0.07 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-12 21:37:59.439059) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 9 secs) (at 2016-10-12 21:37:59.439881) =========
'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-018
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-018
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-018
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=9.901000
========= master_lag: 0.01 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 9 secs) (at 2016-10-12 21:38:09.353023) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-12 21:38:09.353403) =========
'bash' '-c' u'python archiver_client.py mozharness --repo integration/autoland --rev 86499a089946f1f24b1e81a88623749cda6dc5ce --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 86499a089946f1f24b1e81a88623749cda6dc5ce --destination scripts --debug']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-018
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-018
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-018
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-10-12 21:38:09,684 truncating revision to first 12 chars
2016-10-12 21:38:09,684 Setting DEBUG logging.
2016-10-12 21:38:09,684 attempt 1/10
2016-10-12 21:38:09,684 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/86499a089946?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2016-10-12 21:38:10,240 unpacking tar archive at: autoland-86499a089946/testing/mozharness/
2016-10-12 21:38:10,388 skipping symlink on windows: configs/single_locale/linux32.py
program finished with exit code 0
elapsedTime=1.195000
========= master_lag: 0.12 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-12 21:38:10.668459) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-12 21:38:10.668769) =========
script_repo_revision: 86499a089946f1f24b1e81a88623749cda6dc5ce
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-12 21:38:10.669198) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-12 21:38:10.669466) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-12 21:38:10.680727) =========
========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 2 secs) (at 2016-10-12 21:38:10.681250) =========
'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Autoland' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Autoland'
in dir C:\slave\test\. (timeout 3600 secs) (maxTime 7200 secs)
watching logfiles {}
argv: ['c:/mozilla-build/python27/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-018
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-018
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-018
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
21:38:11 INFO - MultiFileLogger online at 20161012 21:38:11 in C:\slave\test
21:38:11 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland
21:38:11 INFO - Dumping config to C:\slave\test\logs\localconfig.json.
21:38:11 INFO - {'append_to_log': False,
21:38:11 INFO - 'base_work_dir': 'C:\\slave\\test',
21:38:11 INFO - 'blob_upload_branch': 'Autoland',
21:38:11 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt',
21:38:11 INFO - 'branch': 'Autoland',
21:38:11 INFO - 'buildbot_json_path': 'buildprops.json',
21:38:11 INFO - 'config_files': ('talos/windows_config.py',),
21:38:11 INFO - 'default_actions': ('clobber',
21:38:11 INFO - 'read-buildbot-config',
21:38:11 INFO - 'download-and-extract',
21:38:11 INFO - 'populate-webroot',
21:38:11 INFO - 'create-virtualenv',
21:38:11 INFO - 'install',
21:38:11 INFO - 'run-tests'),
21:38:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
21:38:11 INFO - 'download_minidump_stackwalk': True,
21:38:11 INFO - 'download_symbols': 'ondemand',
21:38:11 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python',
21:38:11 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'),
21:38:11 INFO - 'hg': 'c:/mozilla-build/hg/hg',
21:38:11 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python',
21:38:11 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'),
21:38:11 INFO - 'python': 'c:/mozilla-build/python27/python.exe',
21:38:11 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe',
21:38:11 INFO - 'C:/mozilla-build/tooltool.py'),
21:38:11 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe',
21:38:11 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')},
21:38:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
21:38:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
21:38:11 INFO - 'installer_path': 'installer.exe',
21:38:11 INFO - 'log_level': 'info',
21:38:11 INFO - 'log_name': 'talos',
21:38:11 INFO - 'log_to_console': True,
21:38:11 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe',
21:38:11 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe',
21:38:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest',
21:38:11 INFO - 'opt_config_files': (),
21:38:11 INFO - 'pip_index': False,
21:38:11 INFO - 'sps_profile': False,
21:38:11 INFO - 'sps_profile_interval': 0,
21:38:11 INFO - 'suite': 'dromaeojs',
21:38:11 INFO - 'system_bits': '32',
21:38:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
21:38:11 INFO - 'title': 'T-W732-IX-018',
21:38:11 INFO - 'use_talos_json': True,
21:38:11 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'),
21:38:11 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv',
21:38:11 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll',
21:38:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
21:38:11 INFO - 'work_dir': 'build'}
21:38:11 INFO - [mozharness: 2016-10-13 04:38:11.208000Z] Running clobber step.
21:38:11 INFO - Running pre-action listener: _resource_record_pre_action
21:38:11 INFO - Running main action method: clobber
21:38:11 INFO - rmtree: C:\slave\test\build
21:38:11 INFO - Using _rmtree_windows ...
21:38:11 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1
21:39:22 INFO - Running post-action listener: _resource_record_post_action
21:39:22 INFO - [mozharness: 2016-10-13 04:39:22.352000Z] Finished clobber step (success)
21:39:22 INFO - [mozharness: 2016-10-13 04:39:22.352000Z] Running read-buildbot-config step.
21:39:22 INFO - Running pre-action listener: _resource_record_pre_action
21:39:22 INFO - Running main action method: read_buildbot_config
21:39:22 INFO - Using buildbot properties:
21:39:22 INFO - {
21:39:22 INFO - "project": "",
21:39:22 INFO - "product": "firefox",
21:39:22 INFO - "script_repo_revision": "production",
21:39:22 INFO - "builddir": "autoland_win7_ix_test-dromaeojs",
21:39:22 INFO - "repository": "",
21:39:22 INFO - "buildername": "Windows 7 32-bit autoland pgo talos dromaeojs",
21:39:22 INFO - "buildid": "20161012213756",
21:39:22 INFO - "stage_platform": "win32-pgo",
21:39:22 INFO - "basedir": "C:\\slave\\test",
21:39:22 INFO - "buildnumber": 131,
21:39:22 INFO - "platform": "win7_ix",
21:39:22 INFO - "master": "http://buildbot-master127.bb.releng.scl3.mozilla.com:8201/",
21:39:22 INFO - "slavebuilddir": "test",
21:39:22 INFO - "branch": "autoland",
21:39:22 INFO - "revision": "86499a089946f1f24b1e81a88623749cda6dc5ce",
21:39:22 INFO - "slavename": "t-w732-ix-018",
21:39:22 INFO - "builduid": "991b1904079343db819c49ccbc6a6180",
21:39:22 INFO - "repo_path": "integration/autoland"
21:39:22 INFO - }
21:39:22 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/autoland-win32-pgo/1476322204/firefox-52.0a1.en-US.win32.test_packages.json.
21:39:22 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/autoland-win32-pgo/1476322204/firefox-52.0a1.en-US.win32.zip.
21:39:22 INFO - Running post-action listener: _resource_record_post_action
21:39:22 INFO - [mozharness: 2016-10-13 04:39:22.355000Z] Finished read-buildbot-config step (success)
21:39:22 INFO - [mozharness: 2016-10-13 04:39:22.356000Z] Running download-and-extract step.
21:39:22 INFO - Running pre-action listener: _resource_record_pre_action
21:39:22 INFO - Running main action method: download_and_extract
21:39:22 INFO - mkdir: C:\slave\test\build\tests
21:39:22 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')]}
21:39:22 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/autoland-win32-pgo/1476322204/firefox-52.0a1.en-US.win32.test_packages.json
21:39:22 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/autoland-win32-pgo/1476322204/firefox-52.0a1.en-US.win32.test_packages.json to C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json
21:39:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/autoland-win32-pgo/1476322204/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
21:39:22 INFO - Downloaded 1218 bytes.
21:39:22 INFO - Reading from file C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json
21:39:22 INFO - Using the following test package requirements:
21:39:22 INFO - {u'common': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
21:39:22 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
21:39:22 INFO - u'firefox-52.0a1.en-US.win32.cppunittest.tests.zip'],
21:39:22 INFO - u'gtest': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
21:39:22 INFO - u'jittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
21:39:22 INFO - u'jsshell-win32.zip'],
21:39:22 INFO - u'mochitest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
21:39:22 INFO - u'firefox-52.0a1.en-US.win32.mochitest.tests.zip'],
21:39:22 INFO - u'mozbase': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
21:39:22 INFO - u'reftest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
21:39:22 INFO - u'firefox-52.0a1.en-US.win32.reftest.tests.zip'],
21:39:22 INFO - u'talos': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
21:39:22 INFO - u'firefox-52.0a1.en-US.win32.talos.tests.zip'],
21:39:22 INFO - u'web-platform': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
21:39:22 INFO - u'firefox-52.0a1.en-US.win32.web-platform.tests.zip'],
21:39:22 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
21:39:22 INFO - u'firefox-52.0a1.en-US.win32.xpcshell.tests.zip']}
21:39:22 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip'] for test suite category: common
21:39:22 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/autoland-win32-pgo/1476322204/firefox-52.0a1.en-US.win32.common.tests.zip
21:39:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/autoland-win32-pgo/1476322204/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1
21:39:22 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/autoland-win32-pgo/1476322204/firefox-52.0a1.en-US.win32.common.tests.zip into memory
21:39:22 INFO - Http code: 200
21:39:22 INFO - via: 1.1 8008015354a3ca72f56c382a1d1cfe9f.cloudfront.net (CloudFront)
21:39:22 INFO - x-amz-cf-id: 2ZlAHVxf6tBx5QpBXTgUvc64Fcf3qwAHhi9hAUdYpIigI9PrYTERWQ==
21:39:22 INFO - x-amz-replication-status: COMPLETED
21:39:22 INFO - x-amz-version-id: hI0x.pzkuKxMwlparnsKanrDrw.bqR_Z
21:39:23 INFO - Expected file size: 17268586
21:39:23 INFO - Obtained file size: 17268586
21:39:52 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
21:39:52 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/autoland-win32-pgo/1476322204/firefox-52.0a1.en-US.win32.common.tests.zip
21:39:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/autoland-win32-pgo/1476322204/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1
21:39:52 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/autoland-win32-pgo/1476322204/firefox-52.0a1.en-US.win32.common.tests.zip into memory
21:39:52 INFO - Http code: 200
21:39:52 INFO - via: 1.1 9baadab4e4d95afe7af7bcba342b29c6.cloudfront.net (CloudFront)
21:39:52 INFO - x-amz-cf-id: QuNQwhhRaq212JVHrhblQ3W7cgYmw4SCbVGatGu0wOgr7akyJPRGag==
21:39:52 INFO - x-amz-replication-status: COMPLETED
21:39:52 INFO - x-amz-version-id: hI0x.pzkuKxMwlparnsKanrDrw.bqR_Z
21:39:53 INFO - Expected file size: 17268586
21:39:53 INFO - Obtained file size: 17268586
21:40:04 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/autoland-win32-pgo/1476322204/firefox-52.0a1.en-US.win32.talos.tests.zip
21:40:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/autoland-win32-pgo/1476322204/firefox-52.0a1.en-US.win32.talos.tests.zip'}, attempt #1
21:40:04 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/autoland-win32-pgo/1476322204/firefox-52.0a1.en-US.win32.talos.tests.zip into memory
21:40:04 INFO - Http code: 200
21:40:04 INFO - via: 1.1 23172d761378a3865feafcda78328053.cloudfront.net (CloudFront)
21:40:04 INFO - x-amz-cf-id: r9JGAnZu-3lmB_qayYvMfaKruD8b1wJ5eU7pSpebWWNJvv_-yxSP3g==
21:40:04 INFO - x-amz-replication-status: COMPLETED
21:40:04 INFO - x-amz-version-id: EkUUzuWUYf7619tGJvmw750jQ4prWU42
21:40:05 INFO - Expected file size: 14409006
21:40:05 INFO - Obtained file size: 14409006
21:40:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
21:40:11 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/autoland-win32-pgo/1476322204/firefox-52.0a1.en-US.win32.zip
21:40:11 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/autoland-win32-pgo/1476322204/firefox-52.0a1.en-US.win32.zip to C:\slave\test\build\firefox-52.0a1.en-US.win32.zip
21:40:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/autoland-win32-pgo/1476322204/firefox-52.0a1.en-US.win32.zip', 'file_name': 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.zip'}, attempt #1
21:40:14 INFO - Downloaded 54715764 bytes.
21:40:14 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/autoland-win32-pgo/1476322204/firefox-52.0a1.en-US.win32.zip
21:40:14 INFO - mkdir: C:\slave\test\properties
21:40:14 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url
21:40:14 INFO - Writing to file C:\slave\test\properties\build_url
21:40:14 INFO - Contents:
21:40:14 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/autoland-win32-pgo/1476322204/firefox-52.0a1.en-US.win32.zip
21:40:14 INFO - Running post-action listener: _resource_record_post_action
21:40:14 INFO - Running post-action listener: set_extra_try_arguments
21:40:14 INFO - [mozharness: 2016-10-13 04:40:14.368000Z] Finished download-and-extract step (success)
21:40:14 INFO - [mozharness: 2016-10-13 04:40:14.368000Z] Running populate-webroot step.
21:40:14 INFO - Running pre-action listener: _resource_record_pre_action
21:40:14 INFO - Running main action method: populate_webroot
21:40:14 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']},
21:40:14 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']},
21:40:14 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome',
21:40:14 INFO - u'--tppagecycles',
21:40:14 INFO - u'7'],
21:40:14 INFO - u'tests': [u'tsvgm']}},
21:40:14 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'],
21:40:14 INFO - u'tests': [u'tresize', u'tcanvasmark']},
21:40:14 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']},
21:40:14 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'],
21:40:14 INFO - u'tests': [u'dromaeo_css', u'kraken']},
21:40:14 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']},
21:40:14 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
21:40:14 INFO - u'talos_options': [u'--disable-e10s'],
21:40:14 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
21:40:14 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
21:40:14 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
21:40:14 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
21:40:14 INFO - u'talos_options': [u'--disable-e10s'],
21:40:14 INFO - u'tests': [u'damp', u'tps']},
21:40:14 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
21:40:14 INFO - u'tests': [u'damp', u'tps']},
21:40:14 INFO - u'g3': {u'talos_options': [u'--disable-e10s'],
21:40:14 INFO - u'tests': [u'dromaeo_dom']},
21:40:14 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']},
21:40:14 INFO - u'g4': {u'talos_options': [u'--disable-e10s'],
21:40:14 INFO - u'tests': [u'basic_compositor_video']},
21:40:14 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']},
21:40:14 INFO - u'other': {u'talos_options': [u'--disable-e10s'],
21:40:14 INFO - u'tests': [u'a11yr',
21:40:14 INFO - u'ts_paint',
21:40:14 INFO - u'tpaint',
21:40:14 INFO - u'sessionrestore',
21:40:14 INFO - u'sessionrestore_no_auto_restore',
21:40:14 INFO - u'tabpaint']},
21:40:14 INFO - u'other-e10s': {u'tests': [u'a11yr',
21:40:14 INFO - u'ts_paint',
21:40:14 INFO - u'tpaint',
21:40:14 INFO - u'sessionrestore',
21:40:14 INFO - u'sessionrestore_no_auto_restore',
21:40:14 INFO - u'tabpaint']},
21:40:14 INFO - u'other-e10s_l64': {u'tests': [u'a11yr',
21:40:14 INFO - u'ts_paint',
21:40:14 INFO - u'tpaint',
21:40:14 INFO - u'sessionrestore',
21:40:14 INFO - u'sessionrestore_no_auto_restore',
21:40:14 INFO - u'tabpaint']},
21:40:14 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr',
21:40:14 INFO - u'ts_paint',
21:40:14 INFO - u'tpaint',
21:40:14 INFO - u'sessionrestore',
21:40:14 INFO - u'sessionrestore_no_auto_restore',
21:40:14 INFO - u'tabpaint']},
21:40:14 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'],
21:40:14 INFO - u'tests': [u'a11yr',
21:40:14 INFO - u'ts_paint',
21:40:14 INFO - u'tpaint',
21:40:14 INFO - u'sessionrestore',
21:40:14 INFO - u'sessionrestore_no_auto_restore',
21:40:14 INFO - u'tabpaint']},
21:40:14 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'],
21:40:14 INFO - u'tests': [u'a11yr',
21:40:14 INFO - u'ts_paint',
21:40:14 INFO - u'tpaint',
21:40:14 INFO - u'sessionrestore',
21:40:14 INFO - u'sessionrestore_no_auto_restore',
21:40:14 INFO - u'tabpaint']},
21:40:14 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'],
21:40:14 INFO - u'tests': [u'tsvgx',
21:40:14 INFO - u'tsvgr_opacity',
21:40:14 INFO - u'tart',
21:40:14 INFO - u'tscrollx',
21:40:14 INFO - u'cart']},
21:40:14 INFO - u'svgr-e10s': {u'tests': [u'tsvgx',
21:40:14 INFO - u'tsvgr_opacity',
21:40:14 INFO - u'tart',
21:40:14 INFO - u'tscrollx',
21:40:14 INFO - u'cart']},
21:40:14 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
21:40:14 INFO - u'talos_options': [u'--disable-e10s'],
21:40:14 INFO - u'tests': [u'tp5o']},
21:40:14 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
21:40:14 INFO - u'tests': [u'tp5o']},
21:40:14 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
21:40:14 INFO - u'talos_options': [u'--disable-e10s',
21:40:14 INFO - u'--xperf_path',
21:40:14 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
21:40:14 INFO - u'tests': [u'tp5n']},
21:40:14 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
21:40:14 INFO - u'talos_options': [u'--xperf_path',
21:40:14 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
21:40:14 INFO - u'tests': [u'tp5n']}},
21:40:14 INFO - u'talos.zip': {u'path': u'',
21:40:14 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}}
21:40:14 INFO - Running post-action listener: _resource_record_post_action
21:40:14 INFO - [mozharness: 2016-10-13 04:40:14.462000Z] Finished populate-webroot step (success)
21:40:14 INFO - [mozharness: 2016-10-13 04:40:14.462000Z] Running create-virtualenv step.
21:40:14 INFO - Running pre-action listener: _resource_record_pre_action
21:40:14 INFO - Running main action method: create_virtualenv
21:40:14 INFO - Creating virtualenv C:\slave\test\build/venv
21:40:14 INFO - mkdir: C:\slave\test\build\venv\Scripts
21:40:14 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll
21:40:14 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'!
21:40:14 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
21:40:14 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv
21:40:26 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe
21:40:30 INFO - Installing distribute......................................................................................................................................................................................done.
21:40:31 INFO - Return code: 0
21:40:31 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version']
21:40:31 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version
21:40:31 INFO - Reading from file tmpfile_stdout
21:40:31 INFO - Output received:
21:40:31 INFO - pip 0.8.2 from c:\slave\test\build\venv\lib\site-packages\pip-0.8.2-py2.7.egg (python 2.7)
21:40:31 INFO - Using _rmtree_windows ...
21:40:31 INFO - Using _rmtree_windows ...
21:40:31 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv
21:40:31 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')]}
21:40:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
21:40:31 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')]}
21:40:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
21:40:31 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 0x01811640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018B5E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017BB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C2E90>, '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 0x012DA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01984520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-018', 'USERDOMAIN': 'T-W732-IX-018', '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-018', '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
21:40:31 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
21:40:31 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
21:40:31 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
21:40:31 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
21:40:31 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
21:40:31 INFO - 'COMPUTERNAME': 'T-W732-IX-018',
21:40:31 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
21:40:31 INFO - 'CYGWINBASE': 'C:\\cygwin',
21:40:31 INFO - 'DCLOCATION': 'SCL3',
21:40:31 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
21:40:31 INFO - 'FP_NO_HOST_CHECK': 'NO',
21:40:31 INFO - 'HOMEDRIVE': 'C:',
21:40:31 INFO - 'HOMEPATH': '\\Users\\cltbld',
21:40:31 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
21:40:31 INFO - 'KTS_VERSION': '1.19c',
21:40:31 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
21:40:31 INFO - 'LOGONSERVER': '\\\\T-W732-IX-018',
21:40:31 INFO - 'MONDIR': 'C:\\Monitor_config\\',
21:40:31 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
21:40:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
21:40:31 INFO - 'MOZ_NO_REMOTE': '1',
21:40:31 INFO - 'NO_EM_RESTART': '1',
21:40:31 INFO - 'NUMBER_OF_PROCESSORS': '8',
21:40:31 INFO - 'OS': 'Windows_NT',
21:40:31 INFO - 'OURDRIVE': 'C:',
21:40:31 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
21:40:31 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
21:40:31 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
21:40:31 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
21:40:31 INFO - 'PROCESSOR_LEVEL': '6',
21:40:31 INFO - 'PROCESSOR_REVISION': '1e05',
21:40:31 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
21:40:31 INFO - 'PROGRAMFILES': 'C:\\Program Files',
21:40:31 INFO - 'PROMPT': '$P$G',
21:40:31 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
21:40:31 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
21:40:31 INFO - 'PUBLIC': 'C:\\Users\\Public',
21:40:31 INFO - 'PWD': 'C:\\slave\\test',
21:40:31 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
21:40:31 INFO - 'SLAVEDIR': 'C:\\slave\\',
21:40:31 INFO - 'SYSTEMDRIVE': 'C:',
21:40:31 INFO - 'SYSTEMROOT': 'C:\\windows',
21:40:31 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:40:31 INFO - 'TEST1': 'testie',
21:40:31 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:40:31 INFO - 'USERDOMAIN': 'T-W732-IX-018',
21:40:31 INFO - 'USERNAME': 'cltbld',
21:40:31 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
21:40:31 INFO - 'WINDIR': 'C:\\windows',
21:40:31 INFO - 'WINDOWS_TRACING_FLAGS': '3',
21:40:31 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
21:40:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
21:40:32 INFO - Ignoring indexes: http://pypi.python.org/simple/
21:40:32 INFO - Downloading/unpacking pip>=1.5
21:40:36 INFO - Running setup.py egg_info for package pip
21:40:36 INFO - warning: no files found matching 'pip\cacert.pem'
21:40:36 INFO - warning: no files found matching '*.html' under directory 'docs'
21:40:36 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
21:40:36 INFO - no previously-included directories found matching 'docs\_build\_sources'
21:40:36 INFO - Installing collected packages: pip
21:40:36 INFO - Found existing installation: pip 0.8.2
21:40:36 INFO - Uninstalling pip:
21:40:36 INFO - Successfully uninstalled pip
21:40:36 INFO - Running setup.py install for pip
21:40:36 INFO - warning: no files found matching 'pip\cacert.pem'
21:40:36 INFO - warning: no files found matching '*.html' under directory 'docs'
21:40:36 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
21:40:36 INFO - no previously-included directories found matching 'docs\_build\_sources'
21:40:36 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts
21:40:36 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts
21:40:36 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts
21:40:36 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts
21:40:36 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts
21:40:36 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts
21:40:36 INFO - Successfully installed pip
21:40:36 INFO - Cleaning up...
21:40:36 INFO - Return code: 0
21:40:36 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv
21:40:36 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')]}
21:40:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
21:40:36 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')]}
21:40:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
21:40:36 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 0x01811640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018B5E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017BB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C2E90>, '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 0x012DA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01984520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-018', 'USERDOMAIN': 'T-W732-IX-018', '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-018', '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
21:40:36 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
21:40:36 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
21:40:36 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
21:40:36 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
21:40:36 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
21:40:36 INFO - 'COMPUTERNAME': 'T-W732-IX-018',
21:40:36 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
21:40:36 INFO - 'CYGWINBASE': 'C:\\cygwin',
21:40:36 INFO - 'DCLOCATION': 'SCL3',
21:40:36 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
21:40:36 INFO - 'FP_NO_HOST_CHECK': 'NO',
21:40:36 INFO - 'HOMEDRIVE': 'C:',
21:40:36 INFO - 'HOMEPATH': '\\Users\\cltbld',
21:40:36 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
21:40:36 INFO - 'KTS_VERSION': '1.19c',
21:40:36 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
21:40:36 INFO - 'LOGONSERVER': '\\\\T-W732-IX-018',
21:40:36 INFO - 'MONDIR': 'C:\\Monitor_config\\',
21:40:36 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
21:40:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
21:40:36 INFO - 'MOZ_NO_REMOTE': '1',
21:40:36 INFO - 'NO_EM_RESTART': '1',
21:40:36 INFO - 'NUMBER_OF_PROCESSORS': '8',
21:40:36 INFO - 'OS': 'Windows_NT',
21:40:36 INFO - 'OURDRIVE': 'C:',
21:40:36 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;',
21:40:36 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
21:40:36 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
21:40:36 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
21:40:36 INFO - 'PROCESSOR_LEVEL': '6',
21:40:36 INFO - 'PROCESSOR_REVISION': '1e05',
21:40:36 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
21:40:36 INFO - 'PROGRAMFILES': 'C:\\Program Files',
21:40:36 INFO - 'PROMPT': '$P$G',
21:40:36 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
21:40:36 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
21:40:36 INFO - 'PUBLIC': 'C:\\Users\\Public',
21:40:36 INFO - 'PWD': 'C:\\slave\\test',
21:40:36 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
21:40:36 INFO - 'SLAVEDIR': 'C:\\slave\\',
21:40:36 INFO - 'SYSTEMDRIVE': 'C:',
21:40:36 INFO - 'SYSTEMROOT': 'C:\\windows',
21:40:36 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:40:36 INFO - 'TEST1': 'testie',
21:40:36 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:40:36 INFO - 'USERDOMAIN': 'T-W732-IX-018',
21:40:36 INFO - 'USERNAME': 'cltbld',
21:40:36 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
21:40:36 INFO - 'WINDIR': 'C:\\windows',
21:40:36 INFO - 'WINDOWS_TRACING_FLAGS': '3',
21:40:36 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
21:40:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
21:40:38 INFO - Ignoring indexes: https://pypi.python.org/simple/
21:40:38 INFO - Downloading/unpacking psutil>=3.1.1
21:40:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
21:40:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
21:40:39 INFO - Installing collected packages: psutil
21:40:39 INFO - Successfully installed psutil
21:40:39 INFO - Cleaning up...
21:40:39 INFO - Return code: 0
21:40:39 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv
21:40:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
21:40:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
21:40:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
21:40:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
21:40:39 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01811640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018B5E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017BB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C2E90>, '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 0x012DA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01984520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-018', 'USERDOMAIN': 'T-W732-IX-018', '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-018', '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
21:40:39 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in C:\slave\test\build
21:40:39 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3
21:40:39 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
21:40:39 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
21:40:39 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
21:40:39 INFO - 'COMPUTERNAME': 'T-W732-IX-018',
21:40:39 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
21:40:39 INFO - 'CYGWINBASE': 'C:\\cygwin',
21:40:39 INFO - 'DCLOCATION': 'SCL3',
21:40:39 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
21:40:39 INFO - 'FP_NO_HOST_CHECK': 'NO',
21:40:39 INFO - 'HOMEDRIVE': 'C:',
21:40:39 INFO - 'HOMEPATH': '\\Users\\cltbld',
21:40:39 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
21:40:39 INFO - 'KTS_VERSION': '1.19c',
21:40:39 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
21:40:39 INFO - 'LOGONSERVER': '\\\\T-W732-IX-018',
21:40:39 INFO - 'MONDIR': 'C:\\Monitor_config\\',
21:40:39 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
21:40:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
21:40:39 INFO - 'MOZ_NO_REMOTE': '1',
21:40:39 INFO - 'NO_EM_RESTART': '1',
21:40:39 INFO - 'NUMBER_OF_PROCESSORS': '8',
21:40:39 INFO - 'OS': 'Windows_NT',
21:40:39 INFO - 'OURDRIVE': 'C:',
21:40:39 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
21:40:39 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
21:40:39 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
21:40:39 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
21:40:39 INFO - 'PROCESSOR_LEVEL': '6',
21:40:39 INFO - 'PROCESSOR_REVISION': '1e05',
21:40:39 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
21:40:39 INFO - 'PROGRAMFILES': 'C:\\Program Files',
21:40:39 INFO - 'PROMPT': '$P$G',
21:40:39 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
21:40:39 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
21:40:39 INFO - 'PUBLIC': 'C:\\Users\\Public',
21:40:39 INFO - 'PWD': 'C:\\slave\\test',
21:40:39 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
21:40:39 INFO - 'SLAVEDIR': 'C:\\slave\\',
21:40:39 INFO - 'SYSTEMDRIVE': 'C:',
21:40:39 INFO - 'SYSTEMROOT': 'C:\\windows',
21:40:39 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:40:39 INFO - 'TEST1': 'testie',
21:40:39 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:40:39 INFO - 'USERDOMAIN': 'T-W732-IX-018',
21:40:39 INFO - 'USERNAME': 'cltbld',
21:40:39 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
21:40:39 INFO - 'WINDIR': 'C:\\windows',
21:40:39 INFO - 'WINDOWS_TRACING_FLAGS': '3',
21:40:39 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
21:40:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
21:40:39 INFO - Ignoring indexes: https://pypi.python.org/simple/
21:40:39 INFO - Downloading/unpacking mozsystemmonitor==0.3
21:40:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
21:40:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
21:40:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz
21:40:40 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor
21:40:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in c:\slave\test\build\venv\lib\site-packages (from mozsystemmonitor==0.3)
21:40:40 INFO - Installing collected packages: mozsystemmonitor
21:40:40 INFO - Running setup.py install for mozsystemmonitor
21:40:41 INFO - Successfully installed mozsystemmonitor
21:40:41 INFO - Cleaning up...
21:40:41 INFO - Return code: 0
21:40:41 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv
21:40: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')]}
21:40:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
21:40: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')]}
21:40:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
21:40:41 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01811640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018B5E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017BB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C2E90>, '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 0x012DA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01984520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-018', 'USERDOMAIN': 'T-W732-IX-018', '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-018', '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
21:40:41 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in C:\slave\test\build
21:40:41 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1
21:40:41 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
21:40:41 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
21:40:41 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
21:40:41 INFO - 'COMPUTERNAME': 'T-W732-IX-018',
21:40:41 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
21:40:41 INFO - 'CYGWINBASE': 'C:\\cygwin',
21:40:41 INFO - 'DCLOCATION': 'SCL3',
21:40:41 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
21:40:41 INFO - 'FP_NO_HOST_CHECK': 'NO',
21:40:41 INFO - 'HOMEDRIVE': 'C:',
21:40:41 INFO - 'HOMEPATH': '\\Users\\cltbld',
21:40:41 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
21:40:41 INFO - 'KTS_VERSION': '1.19c',
21:40:41 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
21:40:41 INFO - 'LOGONSERVER': '\\\\T-W732-IX-018',
21:40:41 INFO - 'MONDIR': 'C:\\Monitor_config\\',
21:40:41 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
21:40:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
21:40:41 INFO - 'MOZ_NO_REMOTE': '1',
21:40:41 INFO - 'NO_EM_RESTART': '1',
21:40:41 INFO - 'NUMBER_OF_PROCESSORS': '8',
21:40:41 INFO - 'OS': 'Windows_NT',
21:40:41 INFO - 'OURDRIVE': 'C:',
21:40: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;',
21:40:41 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
21:40:41 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
21:40:41 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
21:40:41 INFO - 'PROCESSOR_LEVEL': '6',
21:40:41 INFO - 'PROCESSOR_REVISION': '1e05',
21:40:41 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
21:40:41 INFO - 'PROGRAMFILES': 'C:\\Program Files',
21:40:41 INFO - 'PROMPT': '$P$G',
21:40:41 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
21:40:41 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
21:40:41 INFO - 'PUBLIC': 'C:\\Users\\Public',
21:40:41 INFO - 'PWD': 'C:\\slave\\test',
21:40:41 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
21:40:41 INFO - 'SLAVEDIR': 'C:\\slave\\',
21:40:41 INFO - 'SYSTEMDRIVE': 'C:',
21:40:41 INFO - 'SYSTEMROOT': 'C:\\windows',
21:40:41 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:40:41 INFO - 'TEST1': 'testie',
21:40:41 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:40:41 INFO - 'USERDOMAIN': 'T-W732-IX-018',
21:40:41 INFO - 'USERNAME': 'cltbld',
21:40:41 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
21:40:41 INFO - 'WINDIR': 'C:\\windows',
21:40:41 INFO - 'WINDOWS_TRACING_FLAGS': '3',
21:40:41 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
21:40:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
21:40:41 INFO - Ignoring indexes: https://pypi.python.org/simple/
21:40:41 INFO - Downloading/unpacking jsonschema==2.5.1
21:40:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
21:40:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
21:40:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl
21:40:42 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1)
21:40: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
21:40: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
21:40:42 INFO - Downloading functools32-3.2.3-2.tar.gz
21:40:42 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32
21:40:42 INFO - warning: no files found matching '*.txt'
21:40:42 INFO - no previously-included directories found matching 'build'
21:40:42 INFO - no previously-included directories found matching 'dist'
21:40:42 INFO - no previously-included directories found matching '.git*'
21:40:42 INFO - Installing collected packages: jsonschema, functools32
21:40:43 INFO - Running setup.py install for functools32
21:40:43 INFO - warning: no files found matching '*.txt'
21:40:43 INFO - no previously-included directories found matching 'build'
21:40:43 INFO - no previously-included directories found matching 'dist'
21:40:43 INFO - no previously-included directories found matching '.git*'
21:40:43 INFO - Successfully installed jsonschema functools32
21:40:43 INFO - Cleaning up...
21:40:43 INFO - Return code: 0
21:40:43 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv
21:40: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')]}
21:40:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
21:40: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')]}
21:40:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
21:40:43 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01811640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018B5E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017BB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C2E90>, '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 0x012DA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01984520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-018', 'USERDOMAIN': 'T-W732-IX-018', '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-018', '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
21:40:43 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in C:\slave\test\build
21:40:43 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2
21:40:43 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
21:40:43 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
21:40:43 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
21:40:43 INFO - 'COMPUTERNAME': 'T-W732-IX-018',
21:40:43 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
21:40:43 INFO - 'CYGWINBASE': 'C:\\cygwin',
21:40:43 INFO - 'DCLOCATION': 'SCL3',
21:40:43 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
21:40:43 INFO - 'FP_NO_HOST_CHECK': 'NO',
21:40:43 INFO - 'HOMEDRIVE': 'C:',
21:40:43 INFO - 'HOMEPATH': '\\Users\\cltbld',
21:40:43 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
21:40:43 INFO - 'KTS_VERSION': '1.19c',
21:40:43 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
21:40:43 INFO - 'LOGONSERVER': '\\\\T-W732-IX-018',
21:40:43 INFO - 'MONDIR': 'C:\\Monitor_config\\',
21:40:43 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
21:40:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
21:40:43 INFO - 'MOZ_NO_REMOTE': '1',
21:40:43 INFO - 'NO_EM_RESTART': '1',
21:40:43 INFO - 'NUMBER_OF_PROCESSORS': '8',
21:40:43 INFO - 'OS': 'Windows_NT',
21:40:43 INFO - 'OURDRIVE': 'C:',
21:40: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;',
21:40:43 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
21:40:43 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
21:40:43 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
21:40:43 INFO - 'PROCESSOR_LEVEL': '6',
21:40:43 INFO - 'PROCESSOR_REVISION': '1e05',
21:40:43 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
21:40:43 INFO - 'PROGRAMFILES': 'C:\\Program Files',
21:40:43 INFO - 'PROMPT': '$P$G',
21:40:43 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
21:40:43 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
21:40:43 INFO - 'PUBLIC': 'C:\\Users\\Public',
21:40:43 INFO - 'PWD': 'C:\\slave\\test',
21:40:43 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
21:40:43 INFO - 'SLAVEDIR': 'C:\\slave\\',
21:40:43 INFO - 'SYSTEMDRIVE': 'C:',
21:40:43 INFO - 'SYSTEMROOT': 'C:\\windows',
21:40:43 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:40:43 INFO - 'TEST1': 'testie',
21:40:43 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:40:43 INFO - 'USERDOMAIN': 'T-W732-IX-018',
21:40:43 INFO - 'USERNAME': 'cltbld',
21:40:43 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
21:40:43 INFO - 'WINDIR': 'C:\\windows',
21:40:43 INFO - 'WINDOWS_TRACING_FLAGS': '3',
21:40:43 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
21:40:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
21:40:44 INFO - Ignoring indexes: https://pypi.python.org/simple/
21:40:44 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages
21:40:44 INFO - Cleaning up...
21:40:44 INFO - Return code: 0
21:40:44 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv
21:40:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
21:40:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
21:40:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
21:40:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
21:40:44 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01811640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018B5E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017BB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C2E90>, '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 0x012DA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01984520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-018', 'USERDOMAIN': 'T-W732-IX-018', '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-018', '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
21:40:44 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in C:\slave\test\build
21:40:44 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4
21:40:44 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
21:40:44 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
21:40:44 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
21:40:44 INFO - 'COMPUTERNAME': 'T-W732-IX-018',
21:40:44 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
21:40:44 INFO - 'CYGWINBASE': 'C:\\cygwin',
21:40:44 INFO - 'DCLOCATION': 'SCL3',
21:40:44 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
21:40:44 INFO - 'FP_NO_HOST_CHECK': 'NO',
21:40:44 INFO - 'HOMEDRIVE': 'C:',
21:40:44 INFO - 'HOMEPATH': '\\Users\\cltbld',
21:40:44 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
21:40:44 INFO - 'KTS_VERSION': '1.19c',
21:40:44 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
21:40:44 INFO - 'LOGONSERVER': '\\\\T-W732-IX-018',
21:40:44 INFO - 'MONDIR': 'C:\\Monitor_config\\',
21:40:44 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
21:40:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
21:40:44 INFO - 'MOZ_NO_REMOTE': '1',
21:40:44 INFO - 'NO_EM_RESTART': '1',
21:40:44 INFO - 'NUMBER_OF_PROCESSORS': '8',
21:40:44 INFO - 'OS': 'Windows_NT',
21:40:44 INFO - 'OURDRIVE': 'C:',
21:40:44 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
21:40:44 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
21:40:44 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
21:40:44 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
21:40:44 INFO - 'PROCESSOR_LEVEL': '6',
21:40:44 INFO - 'PROCESSOR_REVISION': '1e05',
21:40:44 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
21:40:44 INFO - 'PROGRAMFILES': 'C:\\Program Files',
21:40:44 INFO - 'PROMPT': '$P$G',
21:40:44 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
21:40:44 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
21:40:44 INFO - 'PUBLIC': 'C:\\Users\\Public',
21:40:44 INFO - 'PWD': 'C:\\slave\\test',
21:40:44 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
21:40:44 INFO - 'SLAVEDIR': 'C:\\slave\\',
21:40:44 INFO - 'SYSTEMDRIVE': 'C:',
21:40:44 INFO - 'SYSTEMROOT': 'C:\\windows',
21:40:44 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:40:44 INFO - 'TEST1': 'testie',
21:40:44 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:40:44 INFO - 'USERDOMAIN': 'T-W732-IX-018',
21:40:44 INFO - 'USERNAME': 'cltbld',
21:40:44 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
21:40:44 INFO - 'WINDIR': 'C:\\windows',
21:40:44 INFO - 'WINDOWS_TRACING_FLAGS': '3',
21:40:44 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
21:40:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
21:40:44 INFO - Ignoring indexes: https://pypi.python.org/simple/
21:40:44 INFO - Downloading/unpacking blobuploader==1.2.4
21:40: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
21:40: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
21:40:45 INFO - Downloading blobuploader-1.2.4.tar.gz
21:40:45 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader
21:40:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4)
21:40: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
21:40: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
21:40:45 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests
21:40:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4)
21:40: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
21:40: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
21:40:46 INFO - Downloading docopt-0.6.1.tar.gz
21:40:46 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt
21:40:46 INFO - Installing collected packages: blobuploader, requests, docopt
21:40:46 INFO - Running setup.py install for blobuploader
21:40:46 INFO - Running setup.py install for requests
21:40:47 INFO - Running setup.py install for docopt
21:40:47 INFO - Successfully installed blobuploader requests docopt
21:40:47 INFO - Cleaning up...
21:40:48 INFO - Return code: 0
21:40:48 INFO - Installing None into virtualenv C:\slave\test\build/venv
21:40:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
21:40:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
21:40:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
21:40:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
21:40:48 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--no-deps', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01811640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018B5E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017BB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C2E90>, '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 0x012DA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01984520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-018', 'USERDOMAIN': 'T-W732-IX-018', '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-018', '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
21:40:48 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
21:40:48 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
21:40:48 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
21:40:48 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
21:40:48 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
21:40:48 INFO - 'COMPUTERNAME': 'T-W732-IX-018',
21:40:48 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
21:40:48 INFO - 'CYGWINBASE': 'C:\\cygwin',
21:40:48 INFO - 'DCLOCATION': 'SCL3',
21:40:48 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
21:40:48 INFO - 'FP_NO_HOST_CHECK': 'NO',
21:40:48 INFO - 'HOMEDRIVE': 'C:',
21:40:48 INFO - 'HOMEPATH': '\\Users\\cltbld',
21:40:48 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
21:40:48 INFO - 'KTS_VERSION': '1.19c',
21:40:48 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
21:40:48 INFO - 'LOGONSERVER': '\\\\T-W732-IX-018',
21:40:48 INFO - 'MONDIR': 'C:\\Monitor_config\\',
21:40:48 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
21:40:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
21:40:48 INFO - 'MOZ_NO_REMOTE': '1',
21:40:48 INFO - 'NO_EM_RESTART': '1',
21:40:48 INFO - 'NUMBER_OF_PROCESSORS': '8',
21:40:48 INFO - 'OS': 'Windows_NT',
21:40:48 INFO - 'OURDRIVE': 'C:',
21:40:48 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;',
21:40:48 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
21:40:48 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
21:40:48 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
21:40:48 INFO - 'PROCESSOR_LEVEL': '6',
21:40:48 INFO - 'PROCESSOR_REVISION': '1e05',
21:40:48 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
21:40:48 INFO - 'PROGRAMFILES': 'C:\\Program Files',
21:40:48 INFO - 'PROMPT': '$P$G',
21:40:48 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
21:40:48 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
21:40:48 INFO - 'PUBLIC': 'C:\\Users\\Public',
21:40:48 INFO - 'PWD': 'C:\\slave\\test',
21:40:48 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
21:40:48 INFO - 'SLAVEDIR': 'C:\\slave\\',
21:40:48 INFO - 'SYSTEMDRIVE': 'C:',
21:40:48 INFO - 'SYSTEMROOT': 'C:\\windows',
21:40:48 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:40:48 INFO - 'TEST1': 'testie',
21:40:48 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:40:48 INFO - 'USERDOMAIN': 'T-W732-IX-018',
21:40:48 INFO - 'USERNAME': 'cltbld',
21:40:48 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
21:40:48 INFO - 'WINDIR': 'C:\\windows',
21:40:48 INFO - 'WINDOWS_TRACING_FLAGS': '3',
21:40:48 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
21:40:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
21:40:49 INFO - Ignoring indexes: https://pypi.python.org/simple/
21:40:49 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
21:40:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-k3x6ae-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
21:40:49 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
21:40:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bjvukg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
21:40:49 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
21:40:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ywzamr-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
21:40:49 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
21:40:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-7llrmx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
21:40:49 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
21:40:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cxrasn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
21:40:49 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
21:40:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zufwyp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
21:40:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
21:40:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hekf6s-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
21:40:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
21:40:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-2mts4v-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
21:40:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
21:40:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-4eqrwz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
21:40:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
21:40:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-d0hypf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
21:40:51 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
21:40:51 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mopeoo-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
21:40:51 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
21:40:51 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kro1p3-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
21:40:51 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
21:40:51 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kbcxc3-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
21:40:51 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
21:40:51 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-1a1snr-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
21:40:51 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
21:40:51 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-x_koti-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
21:40:51 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
21:40:51 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-yemujr-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
21:40:51 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
21:40:51 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qse7it-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
21:40:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
21:40:51 INFO - Running setup.py install for manifestparser
21:40:52 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts
21:40:52 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts
21:40:52 INFO - Running setup.py install for mozcrash
21:40:52 INFO - Running setup.py install for mozdebug
21:40:53 INFO - Running setup.py install for mozdevice
21:40:53 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts
21:40:53 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts
21:40:53 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts
21:40:53 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts
21:40:53 INFO - Running setup.py install for mozfile
21:40:54 INFO - Running setup.py install for mozhttpd
21:40:54 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts
21:40:54 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts
21:40:54 INFO - Running setup.py install for mozinfo
21:40:54 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts
21:40:54 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts
21:40:54 INFO - Running setup.py install for mozInstall
21:40:55 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts
21:40:55 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts
21:40:55 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts
21:40:55 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts
21:40:55 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts
21:40:55 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts
21:40:55 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts
21:40:55 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts
21:40:55 INFO - Running setup.py install for mozleak
21:40:55 INFO - Running setup.py install for mozlog
21:40:55 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts
21:40:55 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts
21:40:56 INFO - Running setup.py install for moznetwork
21:40:56 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts
21:40:56 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts
21:40:56 INFO - Running setup.py install for mozprocess
21:40:57 INFO - Running setup.py install for mozprofile
21:40:57 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts
21:40:57 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts
21:40:57 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts
21:40:57 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts
21:40:57 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts
21:40:57 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts
21:40:58 INFO - Running setup.py install for mozrunner
21:40:58 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts
21:40:58 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts
21:40:58 INFO - Running setup.py install for mozscreenshot
21:40:59 INFO - Running setup.py install for moztest
21:40:59 INFO - Running setup.py install for mozversion
21:40:59 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts
21:40:59 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts
21:40:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion
21:40:59 INFO - Cleaning up...
21:40:59 INFO - Return code: 0
21:40:59 INFO - Installing None into virtualenv C:\slave\test\build/venv
21:40:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
21:40:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
21:40:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
21:40:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
21:40:59 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01811640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018B5E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017BB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C2E90>, '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 0x012DA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01984520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-018', 'USERDOMAIN': 'T-W732-IX-018', '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-018', '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
21:40:59 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test\build\tests\config
21:40:59 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 -r C:\slave\test\build\tests\config\mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub
21:41:00 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
21:41:00 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
21:41:00 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
21:41:00 INFO - 'COMPUTERNAME': 'T-W732-IX-018',
21:41:00 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
21:41:00 INFO - 'CYGWINBASE': 'C:\\cygwin',
21:41:00 INFO - 'DCLOCATION': 'SCL3',
21:41:00 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
21:41:00 INFO - 'FP_NO_HOST_CHECK': 'NO',
21:41:00 INFO - 'HOMEDRIVE': 'C:',
21:41:00 INFO - 'HOMEPATH': '\\Users\\cltbld',
21:41:00 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
21:41:00 INFO - 'KTS_VERSION': '1.19c',
21:41:00 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
21:41:00 INFO - 'LOGONSERVER': '\\\\T-W732-IX-018',
21:41:00 INFO - 'MONDIR': 'C:\\Monitor_config\\',
21:41:00 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
21:41:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
21:41:00 INFO - 'MOZ_NO_REMOTE': '1',
21:41:00 INFO - 'NO_EM_RESTART': '1',
21:41:00 INFO - 'NUMBER_OF_PROCESSORS': '8',
21:41:00 INFO - 'OS': 'Windows_NT',
21:41:00 INFO - 'OURDRIVE': 'C:',
21:41:00 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
21:41:00 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
21:41:00 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
21:41:00 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
21:41:00 INFO - 'PROCESSOR_LEVEL': '6',
21:41:00 INFO - 'PROCESSOR_REVISION': '1e05',
21:41:00 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
21:41:00 INFO - 'PROGRAMFILES': 'C:\\Program Files',
21:41:00 INFO - 'PROMPT': '$P$G',
21:41:00 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
21:41:00 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
21:41:00 INFO - 'PUBLIC': 'C:\\Users\\Public',
21:41:00 INFO - 'PWD': 'C:\\slave\\test',
21:41:00 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
21:41:00 INFO - 'SLAVEDIR': 'C:\\slave\\',
21:41:00 INFO - 'SYSTEMDRIVE': 'C:',
21:41:00 INFO - 'SYSTEMROOT': 'C:\\windows',
21:41:00 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:41:00 INFO - 'TEST1': 'testie',
21:41:00 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:41:00 INFO - 'USERDOMAIN': 'T-W732-IX-018',
21:41:00 INFO - 'USERNAME': 'cltbld',
21:41:00 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
21:41:00 INFO - 'WINDIR': 'C:\\windows',
21:41:00 INFO - 'WINDOWS_TRACING_FLAGS': '3',
21:41:00 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
21:41:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
21:41:00 INFO - Ignoring indexes: https://pypi.python.org/simple/
21:41:00 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
21:41:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-sz_0lr-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
21:41:00 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///C:/slave/test/build/tests/mozbase/manifestparser in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 1))
21:41:00 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
21:41:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-e48bc3-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
21:41:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///C:/slave/test/build/tests/mozbase/mozcrash in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2))
21:41:00 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
21:41:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-tykk0_-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
21:41:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///C:/slave/test/build/tests/mozbase/mozdebug in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 3))
21:41:00 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
21:41:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-3r9s14-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
21:41:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///C:/slave/test/build/tests/mozbase/mozdevice in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4))
21:41:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
21:41:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-veomi4-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
21:41:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///C:/slave/test/build/tests/mozbase/mozfile in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 5))
21:41:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
21:41:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-epqymm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
21:41:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///C:/slave/test/build/tests/mozbase/mozhttpd in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 6))
21:41:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
21:41:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mjdrgx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
21:41:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///C:/slave/test/build/tests/mozbase/mozinfo in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 7))
21:41:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
21:41:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pbbwhd-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
21:41:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///C:/slave/test/build/tests/mozbase/mozinstall in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 8))
21:41:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
21:41:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-yokon7-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
21:41:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///C:/slave/test/build/tests/mozbase/mozleak in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 9))
21:41:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
21:41:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-v5yxcf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
21:41:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///C:/slave/test/build/tests/mozbase/mozlog in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10))
21:41:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
21:41:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-oar5q1-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
21:41:02 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))
21:41:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
21:41:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xhisu2-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
21:41:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///C:/slave/test/build/tests/mozbase/mozprocess in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 12))
21:41:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
21:41:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-4jn9_k-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
21:41:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///C:/slave/test/build/tests/mozbase/mozprofile in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 13))
21:41:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
21:41:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ipgmtl-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
21:41:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///C:/slave/test/build/tests/mozbase/mozrunner in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 14))
21:41:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
21:41:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rvpeby-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
21:41:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///C:/slave/test/build/tests/mozbase/mozscreenshot in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 15))
21:41:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
21:41:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-eymj3c-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
21:41:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///C:/slave/test/build/tests/mozbase/moztest in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 16))
21:41:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
21:41:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-emz_f2-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
21:41:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///C:/slave/test/build/tests/mozbase/mozversion in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 17))
21:41:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==0.17->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2))
21:41:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==0.17->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2))
21:41:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in c:\slave\test\build\venv\lib\site-packages (from mozdebug==0.1->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 3))
21:41:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.48->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4))
21:41:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.48->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4))
21:41:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10))
21:41:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
21:41:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
21:41:03 INFO - Downloading blessings-1.6.tar.gz
21:41:03 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings
21:41:04 INFO - Installing collected packages: blessings
21:41:04 INFO - Running setup.py install for blessings
21:41:04 INFO - Successfully installed blessings
21:41:04 INFO - Cleaning up...
21:41:07 INFO - Return code: 0
21:41:07 INFO - Done creating virtualenv C:\slave\test\build/venv.
21:41:07 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
21:41:07 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
21:41:07 INFO - Reading from file tmpfile_stdout
21:41:07 INFO - Using _rmtree_windows ...
21:41:07 INFO - Using _rmtree_windows ...
21:41:07 INFO - Current package versions:
21:41:07 INFO - blessings == 1.6
21:41:07 INFO - blobuploader == 1.2.4
21:41:07 INFO - docopt == 0.6.1
21:41:07 INFO - functools32 == 3.2.3-2
21:41:07 INFO - jsonschema == 2.5.1
21:41:07 INFO - manifestparser == 1.1
21:41:07 INFO - mozInstall == 1.12
21:41:07 INFO - mozcrash == 0.17
21:41:07 INFO - mozdebug == 0.1
21:41:07 INFO - mozdevice == 0.48
21:41:07 INFO - mozfile == 1.2
21:41:07 INFO - mozhttpd == 0.7
21:41:07 INFO - mozinfo == 0.9
21:41:07 INFO - mozleak == 0.1
21:41:07 INFO - mozlog == 3.4
21:41:07 INFO - moznetwork == 0.27
21:41:07 INFO - mozprocess == 0.23
21:41:07 INFO - mozprofile == 0.28
21:41:07 INFO - mozrunner == 6.12
21:41:07 INFO - mozscreenshot == 0.1
21:41:07 INFO - mozsystemmonitor == 0.3
21:41:07 INFO - moztest == 0.7
21:41:07 INFO - mozversion == 1.4
21:41:07 INFO - psutil == 3.1.1
21:41:07 INFO - requests == 1.2.3
21:41:07 INFO - Installing None into virtualenv C:\slave\test\build/venv
21:41:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
21:41:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
21:41:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
21:41:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
21:41:07 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\talos\\requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01811640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018B5E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017BB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C2E90>, '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 0x012DA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01984520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-018', 'USERDOMAIN': 'T-W732-IX-018', '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-018', '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
21:41:07 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\talos\\requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test\build\tests\talos
21:41:07 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 -r C:\slave\test\build\tests\talos\requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub
21:41:07 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
21:41:07 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
21:41:07 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
21:41:07 INFO - 'COMPUTERNAME': 'T-W732-IX-018',
21:41:07 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
21:41:07 INFO - 'CYGWINBASE': 'C:\\cygwin',
21:41:07 INFO - 'DCLOCATION': 'SCL3',
21:41:07 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
21:41:07 INFO - 'FP_NO_HOST_CHECK': 'NO',
21:41:07 INFO - 'HOMEDRIVE': 'C:',
21:41:07 INFO - 'HOMEPATH': '\\Users\\cltbld',
21:41:07 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
21:41:07 INFO - 'KTS_VERSION': '1.19c',
21:41:07 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
21:41:07 INFO - 'LOGONSERVER': '\\\\T-W732-IX-018',
21:41:07 INFO - 'MONDIR': 'C:\\Monitor_config\\',
21:41:07 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
21:41:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
21:41:07 INFO - 'MOZ_NO_REMOTE': '1',
21:41:07 INFO - 'NO_EM_RESTART': '1',
21:41:07 INFO - 'NUMBER_OF_PROCESSORS': '8',
21:41:07 INFO - 'OS': 'Windows_NT',
21:41:07 INFO - 'OURDRIVE': 'C:',
21:41:07 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
21:41:07 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
21:41:07 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
21:41:07 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
21:41:07 INFO - 'PROCESSOR_LEVEL': '6',
21:41:07 INFO - 'PROCESSOR_REVISION': '1e05',
21:41:07 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
21:41:07 INFO - 'PROGRAMFILES': 'C:\\Program Files',
21:41:07 INFO - 'PROMPT': '$P$G',
21:41:07 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
21:41:07 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
21:41:07 INFO - 'PUBLIC': 'C:\\Users\\Public',
21:41:07 INFO - 'PWD': 'C:\\slave\\test',
21:41:07 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
21:41:07 INFO - 'SLAVEDIR': 'C:\\slave\\',
21:41:07 INFO - 'SYSTEMDRIVE': 'C:',
21:41:07 INFO - 'SYSTEMROOT': 'C:\\windows',
21:41:07 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:41:07 INFO - 'TEST1': 'testie',
21:41:07 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:41:07 INFO - 'USERDOMAIN': 'T-W732-IX-018',
21:41:07 INFO - 'USERNAME': 'cltbld',
21:41:07 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
21:41:07 INFO - 'WINDIR': 'C:\\windows',
21:41:07 INFO - 'WINDOWS_TRACING_FLAGS': '3',
21:41:07 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
21:41:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
21:41:08 INFO - Ignoring indexes: https://pypi.python.org/simple/
21:41:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 1))
21:41:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 2))
21:41:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 3))
21:41:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 4))
21:41:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 5))
21:41:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 6))
21:41:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 7))
21:41:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 8))
21:41:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 9))
21:41:08 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in c:\slave\test\build\venv\lib\site-packages (from mozlog>=3.1->-r C:\slave\test\build\tests\talos\requirements.txt (line 1))
21:41:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test\build\venv\lib\site-packages (from mozhttpd>=0.7->-r C:\slave\test\build\tests\talos\requirements.txt (line 4))
21:41:08 INFO - Cleaning up...
21:41:08 INFO - Return code: 0
21:41:08 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv
21:41:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
21:41:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
21:41:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
21:41:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
21:41:08 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01811640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018B5E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017BB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C2E90>, '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 0x012DA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01984520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-018', 'USERDOMAIN': 'T-W732-IX-018', '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-018', '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
21:41:08 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in C:\slave\test\build
21:41:08 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema
21:41:08 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
21:41:08 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
21:41:08 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
21:41:08 INFO - 'COMPUTERNAME': 'T-W732-IX-018',
21:41:08 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
21:41:08 INFO - 'CYGWINBASE': 'C:\\cygwin',
21:41:08 INFO - 'DCLOCATION': 'SCL3',
21:41:08 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
21:41:08 INFO - 'FP_NO_HOST_CHECK': 'NO',
21:41:08 INFO - 'HOMEDRIVE': 'C:',
21:41:08 INFO - 'HOMEPATH': '\\Users\\cltbld',
21:41:08 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
21:41:08 INFO - 'KTS_VERSION': '1.19c',
21:41:08 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
21:41:08 INFO - 'LOGONSERVER': '\\\\T-W732-IX-018',
21:41:08 INFO - 'MONDIR': 'C:\\Monitor_config\\',
21:41:08 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
21:41:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
21:41:08 INFO - 'MOZ_NO_REMOTE': '1',
21:41:08 INFO - 'NO_EM_RESTART': '1',
21:41:08 INFO - 'NUMBER_OF_PROCESSORS': '8',
21:41:08 INFO - 'OS': 'Windows_NT',
21:41:08 INFO - 'OURDRIVE': 'C:',
21:41:08 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
21:41:08 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
21:41:08 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
21:41:08 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
21:41:08 INFO - 'PROCESSOR_LEVEL': '6',
21:41:08 INFO - 'PROCESSOR_REVISION': '1e05',
21:41:08 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
21:41:08 INFO - 'PROGRAMFILES': 'C:\\Program Files',
21:41:08 INFO - 'PROMPT': '$P$G',
21:41:08 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
21:41:08 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
21:41:08 INFO - 'PUBLIC': 'C:\\Users\\Public',
21:41:08 INFO - 'PWD': 'C:\\slave\\test',
21:41:08 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
21:41:08 INFO - 'SLAVEDIR': 'C:\\slave\\',
21:41:08 INFO - 'SYSTEMDRIVE': 'C:',
21:41:08 INFO - 'SYSTEMROOT': 'C:\\windows',
21:41:08 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:41:08 INFO - 'TEST1': 'testie',
21:41:08 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:41:08 INFO - 'USERDOMAIN': 'T-W732-IX-018',
21:41:08 INFO - 'USERNAME': 'cltbld',
21:41:08 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
21:41:08 INFO - 'WINDIR': 'C:\\windows',
21:41:08 INFO - 'WINDOWS_TRACING_FLAGS': '3',
21:41:08 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
21:41:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
21:41:08 INFO - Ignoring indexes: https://pypi.python.org/simple/
21:41:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages
21:41:08 INFO - Cleaning up...
21:41:08 INFO - Return code: 0
21:41:08 INFO - Running post-action listener: _resource_record_post_action
21:41:08 INFO - Running post-action listener: _start_resource_monitoring
21:41:08 INFO - Starting resource monitoring.
21:41:08 INFO - [mozharness: 2016-10-13 04:41:08.702000Z] Finished create-virtualenv step (success)
21:41:08 INFO - [mozharness: 2016-10-13 04:41:08.702000Z] Running install step.
21:41:08 INFO - Running pre-action listener: _resource_record_pre_action
21:41:08 INFO - Running main action method: install
21:41:08 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
21:41:08 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
21:41:09 INFO - Reading from file tmpfile_stdout
21:41:09 INFO - Using _rmtree_windows ...
21:41:09 INFO - Using _rmtree_windows ...
21:41:09 INFO - Detecting whether we're running mozinstall >=1.0...
21:41:09 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h']
21:41:09 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h
21:41:09 INFO - Reading from file tmpfile_stdout
21:41:09 INFO - Output received:
21:41:09 INFO - Usage: mozinstall-script.py [options] installer
21:41:09 INFO - Options:
21:41:09 INFO - -h, --help show this help message and exit
21:41:09 INFO - -d DEST, --destination=DEST
21:41:09 INFO - Directory to install application into. [default:
21:41:09 INFO - "C:\slave\test"]
21:41:09 INFO - --app=APP Application being installed. [default: firefox]
21:41:09 INFO - Using _rmtree_windows ...
21:41:09 INFO - Using _rmtree_windows ...
21:41:09 INFO - mkdir: C:\slave\test\build\application
21:41:09 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.zip', '--destination', 'C:\\slave\\test\\build\\application']
21:41:09 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py C:\slave\test\build\firefox-52.0a1.en-US.win32.zip --destination C:\slave\test\build\application
21:41:11 INFO - Reading from file tmpfile_stdout
21:41:11 INFO - Output received:
21:41:11 INFO - C:\slave\test\build\application\firefox\firefox.exe
21:41:11 INFO - Using _rmtree_windows ...
21:41:11 INFO - Using _rmtree_windows ...
21:41:11 INFO - Running post-action listener: _resource_record_post_action
21:41:11 INFO - [mozharness: 2016-10-13 04:41:11.778000Z] Finished install step (success)
21:41:11 INFO - [mozharness: 2016-10-13 04:41:11.778000Z] Running run-tests step.
21:41:11 INFO - Running pre-action listener: _resource_record_pre_action
21:41:11 INFO - Running main action method: run_tests
21:41:11 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version']
21:41:11 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version
21:41:11 INFO - Python 2.7.3
21:41:11 INFO - Return code: 0
21:41:11 INFO - grabbing minidump binary from tooltool
21:41:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
21:41:11 INFO - retry: Calling run_command with args: (['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C2E90>, '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 0x012DA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01984520>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1
21:41:11 INFO - Running command: ['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'] in C:\slave\test\build
21:41:11 INFO - Copy/paste: c:/mozilla-build/python27/python.exe C:/mozilla-build/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file c:\builds\relengapi.tok fetch -m C:\slave\test\build\tests\config/tooltool-manifests/win32/releng.manifest -o
21:41:11 INFO - Calling ['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'] with output_timeout 600
21:41:11 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'...
21:41:12 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmpszkwkq
21:41:12 INFO - INFO - File integrity verified, renaming tmpszkwkq to win32-minidump_stackwalk.exe
21:41:12 INFO - Return code: 0
21:41:12 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755
21:41:12 INFO - mkdir: C:\slave\test\build\blobber_upload_dir
21:41:12 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir
21:41:12 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe
21:41:12 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir
21:41:12 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/autoland-win32-pgo/1476322204/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-018', '--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
21:41:12 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --branchName Autoland --suite dromaeojs --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://archive.mozilla.org/pub/firefox/tinderbox-builds/autoland-win32-pgo/1476322204/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-018 --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
21:41:12 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
21:41:12 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
21:41:12 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
21:41:12 INFO - 'COMPUTERNAME': 'T-W732-IX-018',
21:41:12 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
21:41:12 INFO - 'CYGWINBASE': 'C:\\cygwin',
21:41:12 INFO - 'DCLOCATION': 'SCL3',
21:41:12 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
21:41:12 INFO - 'FP_NO_HOST_CHECK': 'NO',
21:41:12 INFO - 'HOMEDRIVE': 'C:',
21:41:12 INFO - 'HOMEPATH': '\\Users\\cltbld',
21:41:12 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
21:41:12 INFO - 'KTS_VERSION': '1.19c',
21:41:12 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
21:41:12 INFO - 'LOGONSERVER': '\\\\T-W732-IX-018',
21:41:12 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir',
21:41:12 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe',
21:41:12 INFO - 'MONDIR': 'C:\\Monitor_config\\',
21:41:12 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
21:41:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
21:41:12 INFO - 'MOZ_NO_REMOTE': '1',
21:41:12 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir',
21:41:12 INFO - 'NO_EM_RESTART': '1',
21:41:12 INFO - 'NUMBER_OF_PROCESSORS': '8',
21:41:12 INFO - 'OS': 'Windows_NT',
21:41:12 INFO - 'OURDRIVE': 'C:',
21:41:12 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;',
21:41:12 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
21:41:12 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
21:41:12 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
21:41:12 INFO - 'PROCESSOR_LEVEL': '6',
21:41:12 INFO - 'PROCESSOR_REVISION': '1e05',
21:41:12 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
21:41:12 INFO - 'PROGRAMFILES': 'C:\\Program Files',
21:41:12 INFO - 'PROMPT': '$P$G',
21:41:12 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
21:41:12 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
21:41:12 INFO - 'PUBLIC': 'C:\\Users\\Public',
21:41:12 INFO - 'PWD': 'C:\\slave\\test',
21:41:12 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos',
21:41:12 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
21:41:12 INFO - 'SLAVEDIR': 'C:\\slave\\',
21:41:12 INFO - 'SYSTEMDRIVE': 'C:',
21:41:12 INFO - 'SYSTEMROOT': 'C:\\windows',
21:41:12 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:41:12 INFO - 'TEST1': 'testie',
21:41:12 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
21:41:12 INFO - 'USERDOMAIN': 'T-W732-IX-018',
21:41:12 INFO - 'USERNAME': 'cltbld',
21:41:12 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
21:41:12 INFO - 'WINDIR': 'C:\\windows',
21:41:12 INFO - 'WINDOWS_TRACING_FLAGS': '3',
21:41:12 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
21:41:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
21:41:12 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/autoland-win32-pgo/1476322204/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-018', '--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
21:41:13 INFO - mozversion application_buildid: 20161012183004
21:41:13 INFO - mozversion application_changeset: 86499a089946f1f24b1e81a88623749cda6dc5ce
21:41:13 INFO - mozversion application_display_name: Nightly
21:41:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
21:41:13 INFO - mozversion application_name: Firefox
21:41:13 INFO - mozversion application_remotingname: firefox
21:41:13 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland
21:41:13 INFO - mozversion application_vendor: Mozilla
21:41:13 INFO - mozversion application_version: 52.0a1
21:41:13 INFO - mozversion platform_buildid: 20161012183004
21:41:13 INFO - mozversion platform_changeset: 86499a089946f1f24b1e81a88623749cda6dc5ce
21:41:13 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland
21:41:13 INFO - mozversion platform_version: 52.0a1
21:41:13 INFO - using testdate: 1476333673
21:41:13 INFO - actual date: 1476333673
21:41:13 INFO - starting webserver on 'localhost:49891'
21:41:13 INFO - SUITE-START | Running 2 tests
21:41:13 INFO - TEST-START | dromaeo_css
21:41:13 INFO - operating with platform_type : w7_
21:41:13 INFO - Initialising browser for dromaeo_css test...
21:41:14 INFO - TEST-INFO | started process 744 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpf0vync\profile http://localhost:49891/getInfo.html)
21:41:23 INFO - PROCESS | 744 | __metrics Screen width/height:1600/1200
21:41:23 INFO - PROCESS | 744 | colorDepth:24
21:41:23 INFO - PROCESS | 744 | Browser inner width/height: 1010/674
21:41:23 INFO - PROCESS | 744 | __metrics
21:41:24 INFO - PROCESS | 744 | 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"
21:41:24 INFO - PROCESS | 744 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
21:41:25 INFO - TEST-INFO | 744: exit 0
21:41:25 INFO - Browser initialized.
21:41:25 INFO - Running cycle 1/1 for dromaeo_css test...
21:41:25 INFO - TEST-INFO | started process 4036 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpf0vync\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1)
21:41:26 INFO - PROCESS | 4036 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0
21:41:37 INFO - PROCESS | 4036 | RSS: Main: 123748352
21:41:37 INFO - PROCESS | 4036 |
21:42:28 INFO - PROCESS | 4036 | 1476333748110 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]
21:44:39 INFO - PROCESS | 4036 | Cycle 1(1): loaded http://localhost:49891/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49891/tests/dromaeo/cssquery-ext.html)
21:44:41 INFO - PROCESS | 4036 | RSS: Main: 266203136
21:44:41 INFO - PROCESS | 4036 |
21:47:44 INFO - PROCESS | 4036 | Cycle 1(1): loaded http://localhost:49891/tests/dromaeo/cssquery-ext.html (next: http://localhost:49891/tests/dromaeo/cssquery-jquery.html)
21:47:44 INFO - PROCESS | 4036 | RSS: Main: 197165056
21:47:44 INFO - PROCESS | 4036 |
21:50:46 INFO - PROCESS | 4036 | Cycle 1(1): loaded http://localhost:49891/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49891/tests/dromaeo/cssquery-mootools.html)
21:50:47 INFO - PROCESS | 4036 | RSS: Main: 175927296
21:50:47 INFO - PROCESS | 4036 |
21:53:49 INFO - PROCESS | 4036 | Cycle 1(1): loaded http://localhost:49891/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49891/tests/dromaeo/cssquery-prototype.html)
21:53:49 INFO - PROCESS | 4036 | RSS: Main: 139300864
21:53:49 INFO - PROCESS | 4036 |
21:56:52 INFO - PROCESS | 4036 | Cycle 1(1): loaded http://localhost:49891/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49891/tests/dromaeo/cssquery-yui.html)
21:56:53 INFO - PROCESS | 4036 | RSS: Main: 133996544
21:56:53 INFO - PROCESS | 4036 |
21:59:55 INFO - PROCESS | 4036 | Cycle 1(1): loaded http://localhost:49891/tests/dromaeo/cssquery-yui.html (next: http://localhost:49891/tests/dromaeo/cssquery-dojo.html)
21:59:55 INFO - PROCESS | 4036 | RSS: Main: 198279168
21:59:55 INFO - PROCESS | 4036 |
21:59:55 INFO - PROCESS | 4036 | __start_tp_report
21:59:55 INFO - PROCESS | 4036 | _x_x_mozilla_page_load
21:59:55 INFO - PROCESS | 4036 | _x_x_mozilla_page_load_details
21:59:55 INFO - PROCESS | 4036 | |i|pagename|runs|
21:59:55 INFO - PROCESS | 4036 | |0;dojo.html;2720.986395068025;2740.739629735175;2766.750992410684;2800.1739486851375;2803.747662710357;4698.7415692136965;4723.456802467717;4780;4811.446683631375;4825.059113472874;4677.83627573035;4697;4697.3658556094915;4741.573258406743;4790;4125.339945608704;4183.665306775466;4206.032612499127;4206.684498662613;4206.852760153395;4129.256733787917;4198.1393814268;4202.810873510699;4203.915921681564;4205.705600607958;3989.9227208653647;4141.213169497801;4193.1404062167185;4193.9580604194025;4205.558416366283;4079.938800917989;4105.466289382391;4150.003999040208;4155.625993660585;4231.238377092122;4067.715259931806;4110.815013324378;4113.074558224405;4197;4199.181159673864;4638.721676699409;4655.813767449298;4677.438707355105;4712.575868171847;4751.382320298339;4914.655974081816;4918.532739389752;5011.974940125312;5016.172331565287;5018.64869459138;4913.6314776391555;4963.677360971526;5055.898882022376;5077.593792496591;5082.974585127087;4615.538446155425;4642.605378542828;4643.88390290242;4648.1168577970075;4652.255639097796;5080.59355251579;5094.4141423736;5098.362704661918;5098.413682426569;5101.285819985205;4795.880102997453;4842.249451335048;4866.878328041827;4883.706977581285;4886.828960986349;4893.706377617284;4912.557869791736;4944.159492886218;4956.5786908112495;4966.8758281043265;4836.250381190851;4910.821402863287;4941.332920055818;4957.578605818474;4981.850544483671;4800.4719480857075;4957.851264462072;4980.327655766498;4985.326980857611;4992.575631071327;3317.5189597508224;3318.854995026712;3337.3659004789497;3341.9665803341654;3387.4918762185375;2976.776741744378;3031.0755219657776;3077.584526088949;3077.615298087739;3103.4413805514773;4640.32715256286;4655;4681.29780532916;4708.4820669726305;4731.810727570927;4779.617630589544;4841.661083724105;4980.825671101494;4985.800567977313;5008.474110218447;4595.563421474954;4640.83757068501;4653;4702.95297047032;4750.5249475052215;4115.52671442782;4128.8348466061625;4148.439960605279;4154.709170358046;4167.541570427251;4997.275395067695;5062.240663900371;5198.584113271005;5314.4951229633125;5350.946490535045;4845.878853028703;4945.1346014448045;4966.503349665005;4998.800047998039;5064.7467626619255;5211.895762084661;5242.213667949685;5250.842474725764;5288.762005709829;5318.601104917224;4911.877203069809;4929.580985616263;4938.703677779345;4946.060248552763;4950.579200767975;5006.07387633289;5049.949500505095;5051.318072060225;5058.494150584912;5059.013492368953;4569.246074397687;4912.92630610534;4928.753562321798;4929.06347793918;4932.876678082933;4616.215243408561;4939.43196532389;4941.407031156285;4947.529984651453;4947.901041979212;4843.491433399442;4845.63657725665;4846.80612775485;4849.9815038842235;4850.151223535938;4845.75771211445;4900.093482705711;4925.064237794808;4963.726995015309;5006;4831.565159135695;4835.709857408567;4953.628477864103;4954.20732682769;4955.5044495550155
21:59:55 INFO - PROCESS | 4036 | |1;ext.html;547.8055290371918;564.2636359550787;575.6258432019187;583.3116922032001;600.2227115884929;27228.86385568072;38847.223055538874;39520.802395988016;39618.603813961854;42256.154876902445;39717;39847.80076099623;40524.59475405252;40559.79720101396;40592.79703601478;8264.75205743827;8404.453710508827;8438.198371154762;8440.902682651247;8448.197421245002;6519.315471875456;6738.494612904027;6900.551464154839;9958.153556947633;10129.898701013006;10261.38431694097;10339.844902326471;10357.016083472057;10358.792824143551;10362.326448780805;10293.125084367837;10299.53652085658;10342.534585943575;10349.948250258776;10355.741106472324;6661.800145995628;6819;9502.90497095034;10036.096751292418;10177.491125443697;35154.47268290952;37595;37853.24293514114;38008.61991380106;38028.619713802786;2897.985510072457;2902.0133154727496;2959.186223788454;3005.549167624852;3060.5256185291314;29356.26609334762;29952.415227127327;30159.849200754074;30341;30575.388492230253;2934.3837794063274;2973.3755911258663;3017.321102751885;3037.711417415342;3052.069118918751;288833.5558322216;300775;301086;302008;302030;42314.788426057676;43373.34939975903;46565;46782.29826552604;47301.29048064282;52315;52461.47538524642;52490.73754631279;52516.21225681618;52627;12897.806532902014;13038.478460861648;13047.934760326327;13087.672808179872;13102.344882755824;8772.956135219283;9318.580663870142;9331.626734930527;9345.485998270024;9383.390079644802;13049.739005220032;13538.864611353958;14252.572422827332;14623.415063397557;14899.702005960036;5766.509846662997;5782.624129431573;5810.937117323374;5825.941740582624;5854.18041474194;793.2226418110168;864.9999999999874;934.45334479329;936.1434287626745;964.7973925475728;26483.54340511252;26489.735102649112;26985.970245957025;27217.455650887267;27848.443031139264;874.2612492443875;899.1008991008991;919.7629188741132;929.9860502092474;966.4587830814767;13902.652433689238;13997.160170389809;14338.426462941365;15078.246087695572;15379.84620153784;233000;665509;670279;672260;672294;46138;46595.13619455184;47478.5252147481;49226;49319.260211096866;1016.11090295991;1035.1046344911747;1077.224398433127;1104.3263609198382;1131.8415421840948;25981.610275846255;27857.41418261048;28113.71886281193;28183.71816281811;28480.857595712718;42010.57989420189;42472.5752742481;42531;42585.5741442594;42590.5740942599;366143;369070;369401;370398;371009;36751.816240918626;38322;38323.425148622235;40428.59571404366;40597;42439.7878010608;42720.78639606782;42876.57123428726;43080.78459607682;43526.347104794084;36400.63599364078;36704.449433257985;36778;37290.44064339092;37499.43750843794;32836.507452387756;35066.824665876506;36369.81815090908;36786.448203277505;36967.63032369749;35553.28893422169;36454.8177259112;36529.63470365262;36549.45175822418;36613.450798237514;31120.53319200259;32395.35209295754;32507.34985300328;32714.182145446553;33633.495497568045;25096;25588.104416345337;25703.357416064748;25750.484990300465;26199.869000654875
21:59:55 INFO - PROCESS | 4036 | |2;jquery.html;291.21807945665876;314.8693292283701;315.7489795612488;326.967303269673;326.9722073623742;13162;17608.20763065662;17919.462416127506;17927.282908683635;18619.255229790826;16864.915675421606;17212.13939303033;17429.82570174298;17465.388711395102;17547.122643867788;5400.513953744162;5481.698506582136;5605.971970140164;5605.99999999998;5606.523445507137;5291.894162116755;5306.522412982831;5501.064818980783;5502;5517.200005999128;5446.074167391553;5461.371942226655;5461.399246082928;5466.371367292752;5468.425815289397;4990.076835785391;5392.433794451585;5402.540784033342;5451.100568406208;5454.699991500466;4419.315006174048;4734.100520901018;4760.285957106427;4766.261229509431;4769.093872164278;22155.889220553792;22251.22120725783;22677.77322226773;22690.886545567333;22719.772802272095;1861.4973957031661;2133.3919832847564;2193.100828660242;2289.277871140116;2727.359070618403;13849.792253116211;13851.376688048962;14275.428982840675;14620.561383158521;14645.340959656842;1736.6179440523065;1745.8341457561512;1797.3888877781487;1815.7730283714536;1857.2570971611328;74727;75190.62404687941;76640.23359766387;76952;78846.6057669708;8821.955890220635;8834.337424693174;8925.419847709878;8959.462432254086;9216.078392160864;10789.56841726338;11350.262232954832;11361.636603607454;12048.698782530331;12065.577704780466;6876.828079298058;6911.2397636259975;6940.271271516418;7016.649167541603;7033.366996970297;6805.251422343538;6838.384545390939;6942.652867356612;6948.200956890022;6949.166100067923;5796.884062318814;5813.215215945793;5879.412058794086;5888.498432899583;5902.2622172228475;1570.866476349523;1602.110828490176;1609.9553566969882;1619.1742211472;1627.1213544685975;874.6545114679793;888.8400087984096;961.053362437998;977.3744803325877;980.3284749946167;18122.45632630997;18633.813661863478;18643.254269829325;18798.718019229724;19179.232830686895;858.347655781598;864.9567521623893;890.5992303463468;922.935394522377;958.2286259561121;9588.66439674608;10249.641262555924;10465.110465610509;10568.471576421149;10608.363498190134;444104;447058;450632;452078;456595;93546;94840.52579737196;96767.51616242014;97893;98563.50718246507;907.6732376344643;910.7540963939699;931.1712575807537;940.3370623710268;942.9905700942903;15966.521004369888;16432.424865129662;16577.66844663124;16614.501564953072;17022;30018.44558552236;40921.38617920674;41314.1737165261;42137.15725685408;43011.56988430201;51890.221646674574;57133;57151;57528.110110641064;58477.415225847195;5863.706814659336;7016.789496315118;7087.291270873077;7100.964495177491;7289.744858929912;8830.514321712368;11588.362640054878;11906.52373905034;11983.101267404805;12424.689382765504;10239.48802559884;10301.793964120825;10417.37495750227;10423.42671153094;10437.16502679799;9619.374740641835;9763.804723905625;9872;10033.347832390871;10166.440845753554;10088.344257623232;10407.271490995558;10425.374477531372;10608.416537090534;10674.199435042248;7252.202257751748;7260;7278.599677017814;7282.089738782653;7309.086364204474;7914.5646989413835;10037.514142236207;10134.847977280495;10202.081812636898;10527.736806579896
21:59:55 INFO - PROCESS | 4036 | |3;mootools.html;1175.9588414405498;1187.2342339191218;1253.9592138525024;1477.1432569109918;1482.3403585404485;6780.4575633949225;7180.605066721341;7216.025836512076;7217.458690598213;7240.239774823646;6371.139896114023;6559.21289445266;6887.827804304895;6979.057827193327;7109.289071092888;1865.3937470322132;1884.9528761780928;1887.867849250553;1888.4145914766466;1892.640398324314;1796.0480945099118;1858.2195477899315;1879.2201236486842;1879.9718004229946;1908.332083770676;1617.579429348372;1663.866890648745;1848.4547058617673;1850.2876392588882;1886.2360743898673;1732.8786984911062;1840.585868179669;1853.2772218834666;1853.527350525621;1859.4700510354485;1764.153206460906;1764.673535395943;1778.484239570523;1802.1169626882863;1802.288096202006;6899.241083480813;7044.823879403056;7058.858822823566;7060.329268719514;7066.929330706678;4057.310257256244;4156.2726522858375;4164.646005089571;4167.52073511547;4168.7290326128705;2627.9605805912925;2698.271466703979;2726.195772247192;2727.8772455239564;2750.9587356189672;826.2646244842095;853.8078932240259;855.4781583234222;862.1766213266316;866.2853146154448;9064.229540489132;9066.54667266634;9070.682526111554;9073.36486445949;9090.77273068172;3675.209829886587;3790.1093243087853;3799.1451923317586;3804.1630841214887;3838.827252773576;7310.4517161213125;7310.96344518274;7330.780076597711;7378.262173782686;7379.520331178456;4197.559256278108;4202.432671589358;4204.852830150991;4213;4214.346776249625;4004.3793212051596;4007.6192761687594;4008.2785098682516;4013.819378127933;4018.8995275118355;2802.8878844846386;2851.8574071296766;2898.869550870216;2900.7099290070823;2973.3904549567287;863.1325517854516;880.0319648386851;881.7927786970057;886.4371124336093;887.3034667785757;696.265439960836;701.2321507948787;744.5309455043288;752.2026651749163;768.9000429944183;4392.209402307615;4548.840790572314;4552.226121559343;4569.451665800125;4670.038583794814;338.4111645736401;339.68748751149;342.62482581573136;348.1644054269722;351.982400879955;2976.0922918509627;3021.773366997484;3026.1526772503735;3091.412631599989;3115.8442077896016;7953;8120.187981201832;8446.704365347183;8452.788680283042;8458.703945361884;7901.288884000468;8241.093479717227;8360.623771930277;8381.245687887997;8412.78968025792;6512.772052978123;6642.900356494656;6677.899831502531;6678.165229346332;6687.732490700318;4723.173444647105;4773.188557945227;4774.236122220428;4824.638152138534;4914.336564563881;7884.645190966306;7916.802079947817;7984.361251099782;7987.041555013436;8016.078151012708;7818.452708310364;7964.123946366011;8078.151794061539;8078.394120440873;8082.636281367235;6221.440070393686;6281.340459251713;6287.528435367274;6298.968505157445;6309.589876658002;6742.460603151834;6850.862982740217;6851.588904665736;6864.656767161722;6874;5791.478766910998;6170.7223174958135;6351.841203969938;6352.714127864349;6385.393387628168;6046.667433291212;6072.089186622042;6153.4154255345675;6185.381461853779;6251.812445626639;5645.379008309164;5673.74468148926;5697.4302569742695;5713.714314284352;5727.6849773262875;3910.784906830038;3920.176762879827;3956.149427873021;3983.900402489961;4128.876133715993;4157.48031496063;4162.354835000579;4174.707770456039;4194.706370554032;4253.149370126049
21:59:55 INFO - PROCESS | 4036 | |4;prototype.html;231.51844164138595;252.37033601165092;256.5728062775479;258.08766012147066;260.75749552915795;4512.2103631864375;4773.713577185367;4790.137775200463;4804.423469183698;5069.695818250908;4959.826406075779;4984.37695288089;4990.976849745803;4992.276119962603;4997.300377947081;3692.4092145256764;3695.0762309422644;3695.7597756145765;3696.611855755147;3712.777233365993;3453.620101788801;3523;3620.746547741659;3640.8907732767925;3642.271545690859;3533.823308834561;3538.4338505839073;3602.711783057362;3619.330423871579;3619.5113659656004;3610.061384040142;3610.2598967211657;3611.656892595199;3612.259486805199;3612.638736126393;3053.832039237841;3110.4712198926236;3118.92591426701;3122;3122.3911337289237;4870.853874383774;4887.242477416006;4888.826681596392;4891.9755401222765;4892.926606100911;4135.32519329672;4238.957610423887;4245.662616275863;4256.553061928484;4257.957420425787;5126.897462050775;5153.526307372482;5155.226715992593;5157.71632560209;5175.146100893348;3277.4428347181038;3509.333226686947;3512.367773800715;3512.8594856205723;3513.55944062933;5134.845954621367;5180.792768289264;5415.9458405415835;5429;5434.64674796137;3212.726918211931;3293.1437826164893;3433.759636825448;3465.8267086645565;3475.600305964796;3276.9513755598455;3379.7803142795638;3407.591089069278;3414;3415.8975230743117;3966.781826999485;4051.6353528182603;4173.97913010433;4175.290200665894;4184.267753143188;5220.4419834280925;5222.947770522323;5237.816676416307;5242.820365417827;5245.842624721265;1213.7013395666552;1214.4595654933469;1218.3177420644467;1225.6568160914958;1230.2925817654884;715.8439120819943;746.2239271157935;752.2064222245583;760.2435576601317;761.6991288441145;4578.298803705829;4587.463199828095;4604.700694454849;4631.258998560214;4637.791299391535;4833.299171620095;4837.540433658797;4848.515148485123;4873.975630121827;4878.902421951541;4569.794359253841;4576.6109880660515;4593.793279302373;4625.953740462553;4664.323673067386;2576.175623800403;2603.9949239086177;2604.7004594471878;2604.7265037171205;2673.211402636227;5738.799142030009;5760.049591817303;5761.567882408836;5771.88456230873;5776.68228247442;5492.693068911067;5493.890122197534;5508.366537848118;5524.475174858382;5533.695646739468;5744.540436765049;5774.249347584787;5781.4507621775865;5793.478586927196;5810;5058.241263810458;5296.708681022581;5420.9186862197885;5439.374471935778;5443.319585051869;5319.4414586467865;5357.062514060101;5362.51737343641;5371.683937435353;5375.81184658535;5753.683547404933;5786.3056433228285;5803.419658034331;5810.215620891295;5811.617236932837;3466.116140384186;3536.8938931832085;3551.6448355164275;3562.28754249146;3569.8215089245955;5240.947590524046;5400.216968539461;5436.700981446058;5437.157240627709;5437.8368648940595;5154.922676159936;5182.585393168462;5249.9737501313775;5262.368515778131;5265.657732247391;5237.790488380422;5267.077516706451;5267.367915850123;5268.683878967274;5273.44628813969;5161.716105614077;5164.882398216713;5198.686584470026;5201.921971170511;5202.271681964452;5069.898602028013;5185.329555278959;5261.790048179851;5277.4194838566955;5278.181881808326;5036.974815125901;5069.721165335942;5192.948070519246;5196.856691527858;5199.5042379658735
21:59:55 INFO - PROCESS | 4036 | |5;yui.html;616.7717944360585;649.2501161158863;687.9931200687993;689.5104475822166;695.9930400695993;14603.539646035391;15108.773368399483;15135.470258540954;15368.385264589402;15521.456749013787;14049.50826721065;14111.647208819784;14136;14141.575752727409;14143.57569272921;639.3031595560838;653.0791583866749;653.6176336842952;656.2649832187956;656.3207080671482;521.1557277210924;531.9494648008433;628.1927722876105;635.586868535451;644.3620815392751;636.4844475974452;639.2265358915695;663.383053760003;672.9831754206159;681.0669382945372;662.344279163627;676.1480534526482;678.592844293425;678.7929681447157;679.7281087564965;385.65676547872414;398.63923149549413;463.7101811367895;472.9905401891977;488.0239520958084;12993.87006129936;13158.86841131586;13337;13821;13861.029727919049;12554.121211515198;12621.368931553478;12667.493300267979;12724.427400766988;12870.742585148339;12380.690482737913;12515.311657858814;12544.81182782259;12645.873541264562;12655.564419625105;4128.318827393477;4149.668026557868;4151.356539736345;4153.7300075494995;4180.6446452051305;73660.26339736588;74031.25968740297;76319.61840190763;76672.61663691646;76862;9641.37331073478;9912.603495860229;10114.089731924016;10179;10209.030142136486;19180.808191917902;19237.807621923883;19375.51561210981;19400.417987460398;19411.126499307564;7097.751578694721;7156.856862862714;7366.668499917517;7516.060492438445;7571;3742.1580144467275;3821.407681809324;3853;3878.3600705883778;3947.276518893221;2605.6352110704515;2674.465106978573;2674.531956907533;2676.6386537817534;2683.5840444731098;500.9273655319958;509.320057722944;509.4548832748923;511.20252406246374;513.1328055586048;523.6857885268885;537.9139337706026;545.0080852847778;583.2010146099785;630.9716062777186;9415.811683766424;9619.230461563058;9787.314887957917;9951;10063.295569310225;539.2962184349383;551.9586031047687;617.2839506172818;633.6261605652687;693.0989713372595;4592.2193227151465;4770.189067858472;4781.282807578891;4814.5907597854575;4892.461829198785;105812;106082.46958765312;108575;109528.4523577393;109753;77750.6112469434;84790.57604711936;86437.56781216053;90955.54522227346;93850.53074734719;79655;86177;89178.10821891698;89551.55224223837;90852.54573727089;9424.151826335663;9642.325037247325;9689.176420004378;9742.75643108928;9785.461799601091;49713;50786.49213507817;51503.227451589;51764;51821.48178518166;70882.29117708757;71249.28750712426;73171.63414182895;73763;74782.62608686922;12626.116171867881;12824.935875320563;12865.807012895002;12890.742185156058;13135.671608209488;13521.932390337985;14376.353064112349;14548.854511455173;14655;15226.162561059247;12660.240385576895;13214.27321497327;13563.525276615272;13865;13894.861051389356;13372.86627133755;13439.932800335935;13506.054576179573;13671.931640341734;13677.931610341884;13423.127496712683;13543.86456135426;13702.520411785938;14077.577672669426;14145.151290922578;10623.309484883457;11120.777584448104;11549.48027338755;11595.304281743123;11605.88394116048;11777.367773677792;11846;11953.581624643095;12009.879901201226;12162.270263784201
21:59:55 INFO - PROCESS | 4036 | __end_tp_report
21:59:55 INFO - PROCESS | 4036 | __start_cc_report
21:59:55 INFO - PROCESS | 4036 | _x_x_mozilla_cycle_collect,1933
21:59:55 INFO - PROCESS | 4036 | __end_cc_report
21:59:55 INFO - PROCESS | 4036 | __startTimestamp1476334795916__endTimestamp
21:59:55 INFO - PROCESS | 4036 |
21:59:55 INFO - PROCESS | 4036 | ------- Summary: start -------
21:59:55 INFO - PROCESS | 4036 | Number of tests: 6
21:59:55 INFO - PROCESS | 4036 |
21:59:55 INFO - PROCESS | 4036 | [#0] dojo.html Cycles:165 Average:4598.35 Median:4836.25 stddev:589.50 (12.2%) stddev-sans-first:572.61
21:59:55 INFO - PROCESS | 4036 | Values: 2721.0 2740.7 2766.8 2800.2 2803.7 4698.7 4723.5 4780.0 4811.4 4825.1 4677.8 4697.0 4697.4 4741.6 4790.0 4125.3 4183.7 4206.0 4206.7 4206.9 4129.3 4198.1 4202.8 4203.9 4205.7 3989.9 4141.2 4193.1 4194.0 4205.6 4079.9 4105.5 4150.0 4155.6 4231.2 4067.7 4110.8 4113.1 4197.0 4199.2 4638.7 4655.8 4677.4 4712.6 4751.4 4914.7 4918.5 5012.0 5016.2 5018.6 4913.6 4963.7 5055.9 5077.6 5083.0 4615.5 4642.6 4643.9 4648.1 4652.3 5080.6 5094.4 5098.4 5098.4 5101.3 4795.9 4842.2 4866.9 4883.7 4886.8 4893.7 4912.6 4944.2 4956.6 4966.9 4836.3 4910.8 4941.3 4957.6 4981.9 4800.5 4957.9 4980.3 4985.3 4992.6 3317.5 3318.9 3337.4 3342.0 3387.5 2976.8 3031.1 3077.6 3077.6 3103.4 4640.3 4655.0 4681.3 4708.5 4731.8 4779.6 4841.7 4980.8 4985.8 5008.5 4595.6 4640.8 4653.0 4703.0 4750.5 4115.5 4128.8 4148.4 4154.7 4167.5 4997.3 5062.2 5198.6 5314.5 5350.9 4845.9 4945.1 4966.5 4998.8 5064.7 5211.9 5242.2 5250.8 5288.8 5318.6 4911.9 4929.6 4938.7 4946.1 4950.6 5006.1 5049.9 5051.3 5058.5 5059.0 4569.2 4912.9 4928.8 4929.1 4932.9 4616.2 4939.4 4941.4 4947.5 4947.9 4843.5 4845.6 4846.8 4850.0 4850.2 4845.8 4900.1 4925.1 4963.7 5006.0 4831.6 4835.7 4953.6 4954.2 4955.5
21:59:55 INFO - PROCESS | 4036 |
21:59:55 INFO - PROCESS | 4036 | [#1] ext.html Cycles:180 Average:55537.19 Median:369235.50 stddev:119433.60 (32.3%) stddev-sans-first:119697.28
21:59:55 INFO - PROCESS | 4036 | Values: 547.8 564.3 575.6 583.3 600.2 27228.9 38847.2 39520.8 39618.6 42256.2 39717.0 39847.8 40524.6 40559.8 40592.8 8264.8 8404.5 8438.2 8440.9 8448.2 6519.3 6738.5 6900.6 9958.2 10129.9 10261.4 10339.8 10357.0 10358.8 10362.3 10293.1 10299.5 10342.5 10349.9 10355.7 6661.8 6819.0 9502.9 10036.1 10177.5 35154.5 37595.0 37853.2 38008.6 38028.6 2898.0 2902.0 2959.2 3005.5 3060.5 29356.3 29952.4 30159.8 30341.0 30575.4 2934.4 2973.4 3017.3 3037.7 3052.1 288833.6 300775.0 301086.0 302008.0 302030.0 42314.8 43373.3 46565.0 46782.3 47301.3 52315.0 52461.5 52490.7 52516.2 52627.0 12897.8 13038.5 13047.9 13087.7 13102.3 8773.0 9318.6 9331.6 9345.5 9383.4 13049.7 13538.9 14252.6 14623.4 14899.7 5766.5 5782.6 5810.9 5825.9 5854.2 793.2 865.0 934.5 936.1 964.8 26483.5 26489.7 26986.0 27217.5 27848.4 874.3 899.1 919.8 930.0 966.5 13902.7 13997.2 14338.4 15078.2 15379.8 233000.0 665509.0 670279.0 672260.0 672294.0 46138.0 46595.1 47478.5 49226.0 49319.3 1016.1 1035.1 1077.2 1104.3 1131.8 25981.6 27857.4 28113.7 28183.7 28480.9 42010.6 42472.6 42531.0 42585.6 42590.6 366143.0 369070.0 369401.0 370398.0 371009.0 36751.8 38322.0 38323.4 40428.6 40597.0 42439.8 42720.8 42876.6 43080.8 43526.3 36400.6 36704.4 36778.0 37290.4 37499.4 32836.5 35066.8 36369.8 36786.4 36967.6 35553.3 36454.8 36529.6 36549.5 36613.5 31120.5 32395.4 32507.3 32714.2 33633.5 25096.0 25588.1 25703.4 25750.5 26199.9
21:59:55 INFO - PROCESS | 4036 |
21:59:55 INFO - PROCESS | 4036 | [#2] jquery.html Cycles:180 Average:27130.56 Median:4747.19 stddev:74589.71 (1571.2%) stddev-sans-first:74771.73
21:59:55 INFO - PROCESS | 4036 | Values: 291.2 314.9 315.7 327.0 327.0 13162.0 17608.2 17919.5 17927.3 18619.3 16864.9 17212.1 17429.8 17465.4 17547.1 5400.5 5481.7 5606.0 5606.0 5606.5 5291.9 5306.5 5501.1 5502.0 5517.2 5446.1 5461.4 5461.4 5466.4 5468.4 4990.1 5392.4 5402.5 5451.1 5454.7 4419.3 4734.1 4760.3 4766.3 4769.1 22155.9 22251.2 22677.8 22690.9 22719.8 1861.5 2133.4 2193.1 2289.3 2727.4 13849.8 13851.4 14275.4 14620.6 14645.3 1736.6 1745.8 1797.4 1815.8 1857.3 74727.0 75190.6 76640.2 76952.0 78846.6 8822.0 8834.3 8925.4 8959.5 9216.1 10789.6 11350.3 11361.6 12048.7 12065.6 6876.8 6911.2 6940.3 7016.6 7033.4 6805.3 6838.4 6942.7 6948.2 6949.2 5796.9 5813.2 5879.4 5888.5 5902.3 1570.9 1602.1 1610.0 1619.2 1627.1 874.7 888.8 961.1 977.4 980.3 18122.5 18633.8 18643.3 18798.7 19179.2 858.3 865.0 890.6 922.9 958.2 9588.7 10249.6 10465.1 10568.5 10608.4 444104.0 447058.0 450632.0 452078.0 456595.0 93546.0 94840.5 96767.5 97893.0 98563.5 907.7 910.8 931.2 940.3 943.0 15966.5 16432.4 16577.7 16614.5 17022.0 30018.4 40921.4 41314.2 42137.2 43011.6 51890.2 57133.0 57151.0 57528.1 58477.4 5863.7 7016.8 7087.3 7101.0 7289.7 8830.5 11588.4 11906.5 11983.1 12424.7 10239.5 10301.8 10417.4 10423.4 10437.2 9619.4 9763.8 9872.0 10033.3 10166.4 10088.3 10407.3 10425.4 10608.4 10674.2 7252.2 7260.0 7278.6 7282.1 7309.1 7914.6 10037.5 10134.8 10202.1 10527.7
21:59:55 INFO - PROCESS | 4036 |
21:59:55 INFO - PROCESS | 4036 | [#3] mootools.html Cycles:180 Average:4533.62 Median:5659.56 stddev:2555.85 (45.2%) stddev-sans-first:2550.56
21:59:55 INFO - PROCESS | 4036 | Values: 1176.0 1187.2 1254.0 1477.1 1482.3 6780.5 7180.6 7216.0 7217.5 7240.2 6371.1 6559.2 6887.8 6979.1 7109.3 1865.4 1885.0 1887.9 1888.4 1892.6 1796.0 1858.2 1879.2 1880.0 1908.3 1617.6 1663.9 1848.5 1850.3 1886.2 1732.9 1840.6 1853.3 1853.5 1859.5 1764.2 1764.7 1778.5 1802.1 1802.3 6899.2 7044.8 7058.9 7060.3 7066.9 4057.3 4156.3 4164.6 4167.5 4168.7 2628.0 2698.3 2726.2 2727.9 2751.0 826.3 853.8 855.5 862.2 866.3 9064.2 9066.5 9070.7 9073.4 9090.8 3675.2 3790.1 3799.1 3804.2 3838.8 7310.5 7311.0 7330.8 7378.3 7379.5 4197.6 4202.4 4204.9 4213.0 4214.3 4004.4 4007.6 4008.3 4013.8 4018.9 2802.9 2851.9 2898.9 2900.7 2973.4 863.1 880.0 881.8 886.4 887.3 696.3 701.2 744.5 752.2 768.9 4392.2 4548.8 4552.2 4569.5 4670.0 338.4 339.7 342.6 348.2 352.0 2976.1 3021.8 3026.2 3091.4 3115.8 7953.0 8120.2 8446.7 8452.8 8458.7 7901.3 8241.1 8360.6 8381.2 8412.8 6512.8 6642.9 6677.9 6678.2 6687.7 4723.2 4773.2 4774.2 4824.6 4914.3 7884.6 7916.8 7984.4 7987.0 8016.1 7818.5 7964.1 8078.2 8078.4 8082.6 6221.4 6281.3 6287.5 6299.0 6309.6 6742.5 6850.9 6851.6 6864.7 6874.0 5791.5 6170.7 6351.8 6352.7 6385.4 6046.7 6072.1 6153.4 6185.4 6251.8 5645.4 5673.7 5697.4 5713.7 5727.7 3910.8 3920.2 3956.1 3983.9 4128.9 4157.5 4162.4 4174.7 4194.7 4253.1
21:59:55 INFO - PROCESS | 4036 |
21:59:55 INFO - PROCESS | 4036 | [#4] prototype.html Cycles:180 Average:4274.70 Median:4888.03 stddev:1372.79 (28.1%) stddev-sans-first:1342.68
21:59:55 INFO - PROCESS | 4036 | Values: 231.5 252.4 256.6 258.1 260.8 4512.2 4773.7 4790.1 4804.4 5069.7 4959.8 4984.4 4991.0 4992.3 4997.3 3692.4 3695.1 3695.8 3696.6 3712.8 3453.6 3523.0 3620.7 3640.9 3642.3 3533.8 3538.4 3602.7 3619.3 3619.5 3610.1 3610.3 3611.7 3612.3 3612.6 3053.8 3110.5 3118.9 3122.0 3122.4 4870.9 4887.2 4888.8 4892.0 4892.9 4135.3 4239.0 4245.7 4256.6 4258.0 5126.9 5153.5 5155.2 5157.7 5175.1 3277.4 3509.3 3512.4 3512.9 3513.6 5134.8 5180.8 5415.9 5429.0 5434.6 3212.7 3293.1 3433.8 3465.8 3475.6 3277.0 3379.8 3407.6 3414.0 3415.9 3966.8 4051.6 4174.0 4175.3 4184.3 5220.4 5222.9 5237.8 5242.8 5245.8 1213.7 1214.5 1218.3 1225.7 1230.3 715.8 746.2 752.2 760.2 761.7 4578.3 4587.5 4604.7 4631.3 4637.8 4833.3 4837.5 4848.5 4874.0 4878.9 4569.8 4576.6 4593.8 4626.0 4664.3 2576.2 2604.0 2604.7 2604.7 2673.2 5738.8 5760.0 5761.6 5771.9 5776.7 5492.7 5493.9 5508.4 5524.5 5533.7 5744.5 5774.2 5781.5 5793.5 5810.0 5058.2 5296.7 5420.9 5439.4 5443.3 5319.4 5357.1 5362.5 5371.7 5375.8 5753.7 5786.3 5803.4 5810.2 5811.6 3466.1 3536.9 3551.6 3562.3 3569.8 5240.9 5400.2 5436.7 5437.2 5437.8 5154.9 5182.6 5250.0 5262.4 5265.7 5237.8 5267.1 5267.4 5268.7 5273.4 5161.7 5164.9 5198.7 5201.9 5202.3 5069.9 5185.3 5261.8 5277.4 5278.2 5037.0 5069.7 5192.9 5196.9 5199.5
21:59:55 INFO - PROCESS | 4036 |
21:59:55 INFO - PROCESS | 4036 | [#5] yui.html Cycles:180 Average:19926.55 Median:4386.43 stddev:28482.32 (649.3%) stddev-sans-first:28525.31
21:59:55 INFO - PROCESS | 4036 | Values: 616.8 649.3 688.0 689.5 696.0 14603.5 15108.8 15135.5 15368.4 15521.5 14049.5 14111.6 14136.0 14141.6 14143.6 639.3 653.1 653.6 656.3 656.3 521.2 531.9 628.2 635.6 644.4 636.5 639.2 663.4 673.0 681.1 662.3 676.1 678.6 678.8 679.7 385.7 398.6 463.7 473.0 488.0 12993.9 13158.9 13337.0 13821.0 13861.0 12554.1 12621.4 12667.5 12724.4 12870.7 12380.7 12515.3 12544.8 12645.9 12655.6 4128.3 4149.7 4151.4 4153.7 4180.6 73660.3 74031.3 76319.6 76672.6 76862.0 9641.4 9912.6 10114.1 10179.0 10209.0 19180.8 19237.8 19375.5 19400.4 19411.1 7097.8 7156.9 7366.7 7516.1 7571.0 3742.2 3821.4 3853.0 3878.4 3947.3 2605.6 2674.5 2674.5 2676.6 2683.6 500.9 509.3 509.5 511.2 513.1 523.7 537.9 545.0 583.2 631.0 9415.8 9619.2 9787.3 9951.0 10063.3 539.3 552.0 617.3 633.6 693.1 4592.2 4770.2 4781.3 4814.6 4892.5 105812.0 106082.5 108575.0 109528.5 109753.0 77750.6 84790.6 86437.6 90955.5 93850.5 79655.0 86177.0 89178.1 89551.6 90852.5 9424.2 9642.3 9689.2 9742.8 9785.5 49713.0 50786.5 51503.2 51764.0 51821.5 70882.3 71249.3 73171.6 73763.0 74782.6 12626.1 12824.9 12865.8 12890.7 13135.7 13521.9 14376.4 14548.9 14655.0 15226.2 12660.2 13214.3 13563.5 13865.0 13894.9 13372.9 13439.9 13506.1 13671.9 13677.9 13423.1 13543.9 13702.5 14077.6 14145.2 10623.3 11120.8 11549.5 11595.3 11605.9 11777.4 11846.0 11953.6 12009.9 12162.3
21:59:55 INFO - PROCESS | 4036 | -------- Summary: end --------
21:59:55 INFO - PROCESS | 4036 |
21:59:56 INFO - TEST-INFO | 4036: exit 0
21:59:57 INFO - TEST-OK | dromaeo_css | took 1123317ms
21:59:57 INFO - TEST-START | kraken
21:59:57 INFO - operating with platform_type : w7_
21:59:57 INFO - Initialising browser for kraken test...
21:59:57 INFO - TEST-INFO | started process 2872 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpomfvpg\profile http://localhost:49891/getInfo.html)
22:00:03 INFO - PROCESS | 2872 | __metrics Screen width/height:1600/1200
22:00:03 INFO - PROCESS | 2872 | colorDepth:24
22:00:03 INFO - PROCESS | 2872 | Browser inner width/height: 1010/674
22:00:03 INFO - PROCESS | 2872 | __metrics
22:00:04 INFO - PROCESS | 2872 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows"
22:00:04 INFO - PROCESS | 2872 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
22:00:04 INFO - PROCESS | 2872 | console.error:
22:00:04 INFO - PROCESS | 2872 | Message: Error: Connection closed before committing the transaction.
22:00:04 INFO - PROCESS | 2872 | Stack:
22:00:04 INFO - PROCESS | 2872 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:937:23
22:00:04 INFO - PROCESS | 2872 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7
22:00:04 INFO - PROCESS | 2872 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11
22:00:04 INFO - PROCESS | 2872 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7
22:00:04 INFO - PROCESS | 2872 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7
22:00:04 INFO - PROCESS | 2872 | TaskImpl_run@resource://gre/modules/Task.jsm:327:15
22:00:04 INFO - PROCESS | 2872 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9
22:00:04 INFO - PROCESS | 2872 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15
22:00:04 INFO - PROCESS | 2872 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5
22:00:04 INFO - PROCESS | 2872 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29
22:00:04 INFO - PROCESS | 2872 | Barrier/this.client.addBlocker/promise13.1%
22:01:02 INFO - TinderboxPrint: I/O read bytes / time
83,512,320 / 924,650
22:01:02 INFO - TinderboxPrint: I/O write bytes / time
475,222,016 / 3,247,460
22:01:02 INFO - TinderboxPrint: CPU idle
8,296.4 (86.9%)
22:01:02 INFO - TinderboxPrint: CPU user
1,211.6 (12.7%)
22:01:02 INFO - install - Wall time: 3s; CPU: 8.0%; Read bytes: 65536; Write bytes: 36676608; Read time: 68410; Write time: 384740
22:01:02 INFO - run-tests - Wall time: 1190s; CPU: 13.0%; Read bytes: 83446784; Write bytes: 402000896; Read time: 856240; Write time: 2836350
22:01:02 INFO - Running post-run listener: _upload_blobber_files
22:01:02 INFO - Blob upload gear active.
22:01:02 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir.
22:01:02 INFO - Files from C:\slave\test\build\blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com
22:01:02 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\venv\\Scripts\\blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', 'C:\\slave\\test\\oauth.txt', '-b', 'Autoland', '-d', 'C:\\slave\\test\\build\\blobber_upload_dir', '--output-manifest', 'C:\\slave\\test\\build\\uploaded_files.json']
22:01:02 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\venv\Scripts\blobberc.py -u https://blobupload.elasticbeanstalk.com -a C:\slave\test\oauth.txt -b Autoland -d C:\slave\test\build\blobber_upload_dir --output-manifest C:\slave\test\build\uploaded_files.json
22:01:03 INFO - (blobuploader) - INFO - Open directory for files ...
22:01:03 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log ...
22:01:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
22:01:03 INFO - (blobuploader) - INFO - Uploading, attempt #1.
22:01:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded
22:01:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
22:01:04 INFO - (blobuploader) - INFO - Done attempting.
22:01:04 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log ...
22:01:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
22:01:04 INFO - (blobuploader) - INFO - Uploading, attempt #1.
22:01:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded
22:01:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
22:01:05 INFO - (blobuploader) - INFO - Done attempting.
22:01:05 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ...
22:01:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
22:01:05 INFO - (blobuploader) - INFO - Uploading, attempt #1.
22:01:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
22:01:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
22:01:12 INFO - (blobuploader) - INFO - Done attempting.
22:01:12 INFO - (blobuploader) - INFO - Iteration through files over.
22:01:12 INFO - Return code: 0
22:01:12 INFO - rmtree: C:\slave\test\build\uploaded_files.json
22:01:12 INFO - Using _rmtree_windows ...
22:01:12 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1
22:01:12 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2592889c85bcd7727d6fec4af7f6111f90b31af62b76289908767473c74aea99cd46652cb16df4bd7e6c84b87887d1926cfe0b07481091b6644e615b38834757", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/835d3f68ae14c0dcce4aa0a8b3de8f20dabe8d45c4f755af34adc6aa6b4a3d2d5c1df17af40f0b0d222f85db65cf5e1907e5c5fc842a5b7f89525841762eccbf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/5e54e179423886b7aaba568982cba902cb8fc4c3c1346af3b2949675a57de2d13f18c2d54e9bd295f836c060f4b07201b8371f1a03674930f39caf6843d5664e"}
22:01:12 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files
22:01:12 INFO - Writing to file C:\slave\test\properties\blobber_files
22:01:12 INFO - Contents:
22:01:12 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2592889c85bcd7727d6fec4af7f6111f90b31af62b76289908767473c74aea99cd46652cb16df4bd7e6c84b87887d1926cfe0b07481091b6644e615b38834757", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/835d3f68ae14c0dcce4aa0a8b3de8f20dabe8d45c4f755af34adc6aa6b4a3d2d5c1df17af40f0b0d222f85db65cf5e1907e5c5fc842a5b7f89525841762eccbf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/5e54e179423886b7aaba568982cba902cb8fc4c3c1346af3b2949675a57de2d13f18c2d54e9bd295f836c060f4b07201b8371f1a03674930f39caf6843d5664e"}
22:01:12 INFO - Running post-run listener: copy_logs_to_upload_dir
22:01:12 INFO - Copying logs to upload dir...
22:01:12 INFO - mkdir: C:\slave\test\build\upload\logs
22:01:12 INFO - Copying logs to upload dir...
22:01:12 INFO - Using _rmtree_windows ...
22:01:12 INFO - Using _rmtree_windows ...
22:01:13 INFO - Using _rmtree_windows ...
22:01:13 INFO - Using _rmtree_windows ...
22:01:13 INFO - Using _rmtree_windows ...
22:01:13 INFO - Using _rmtree_windows ...
22:01:13 INFO - Using _rmtree_windows ...
program finished with exit code 0
elapsedTime=1382.276000
========= master_lag: 0.11 =========
========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 2 secs) (at 2016-10-12 22:01:13.068732) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-12 22:01:13.069932) =========
'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-018
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-018
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-018
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2592889c85bcd7727d6fec4af7f6111f90b31af62b76289908767473c74aea99cd46652cb16df4bd7e6c84b87887d1926cfe0b07481091b6644e615b38834757", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/835d3f68ae14c0dcce4aa0a8b3de8f20dabe8d45c4f755af34adc6aa6b4a3d2d5c1df17af40f0b0d222f85db65cf5e1907e5c5fc842a5b7f89525841762eccbf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/5e54e179423886b7aaba568982cba902cb8fc4c3c1346af3b2949675a57de2d13f18c2d54e9bd295f836c060f4b07201b8371f1a03674930f39caf6843d5664e"}
build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/autoland-win32-pgo/1476322204/firefox-52.0a1.en-US.win32.zip
program finished with exit code 0
elapsedTime=0.102000
build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/autoland-win32-pgo/1476322204/firefox-52.0a1.en-US.win32.zip'
blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2592889c85bcd7727d6fec4af7f6111f90b31af62b76289908767473c74aea99cd46652cb16df4bd7e6c84b87887d1926cfe0b07481091b6644e615b38834757", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/835d3f68ae14c0dcce4aa0a8b3de8f20dabe8d45c4f755af34adc6aa6b4a3d2d5c1df17af40f0b0d222f85db65cf5e1907e5c5fc842a5b7f89525841762eccbf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/5e54e179423886b7aaba568982cba902cb8fc4c3c1346af3b2949675a57de2d13f18c2d54e9bd295f836c060f4b07201b8371f1a03674930f39caf6843d5664e"}'
========= master_lag: 0.01 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-12 22:01:13.185406) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 22:01:13.185731) =========
'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-018
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-018
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-018
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-10-12 22:01:13.297160) =========
========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-10-12 22:01:13.297539) =========
========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-10-12 22:01:13.756819) =========
========= Total master_lag: 0.34 =========