builder: fx-team_win7_ix_test-dromaeojs-pgo
slave: t-w732-ix-096
starttime: 1476534929.12
results: success (0)
buildid: 20161015053455
builduid: 83108166a2b4455cac78f3c5965ee5c1
revision: 44b01519b5668699f2c1a10d362445be59711d35
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-15 05:35:29.117205) =========
master: http://buildbot-master110.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-15 05:35:29.117755) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-15 05:35:29.118033) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-15 05:35:29.196161) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-15 05:35:29.196444) =========
'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-096
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-096
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-096
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
--05:35:29-- 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% 8.84 MB/s
05:35:30 (8.84 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=1.047000
========= master_lag: 0.07 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-15 05:35:30.314907) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 10 secs) (at 2016-10-15 05:35:30.315194) =========
'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-096
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-096
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-096
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
program finished with exit code 0
elapsedTime=10.502000
========= master_lag: 0.01 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 10 secs) (at 2016-10-15 05:35:40.826651) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-15 05:35:40.827034) =========
'bash' '-c' u'python archiver_client.py mozharness --repo integration/fx-team --rev 44b01519b5668699f2c1a10d362445be59711d35 --destination scripts --debug'
in dir C:\slave\test\. (timeout 1200 secs)
watching logfiles {}
argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 44b01519b5668699f2c1a10d362445be59711d35 --destination scripts --debug']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-096
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-096
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-096
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-15 05:35:40,878 truncating revision to first 12 chars
2016-10-15 05:35:40,878 Setting DEBUG logging.
2016-10-15 05:35:40,878 attempt 1/10
2016-10-15 05:35:40,880 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/44b01519b566?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2016-10-15 05:35:42,052 unpacking tar archive at: fx-team-44b01519b566/testing/mozharness/
2016-10-15 05:35:42,171 skipping symlink on windows: configs/single_locale/linux32.py
program finished with exit code 0
elapsedTime=1.829000
========= master_lag: 0.06 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-15 05:35:42.712283) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-15 05:35:42.712588) =========
script_repo_revision: 44b01519b5668699f2c1a10d362445be59711d35
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-15 05:35:42.713053) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-15 05:35:42.713333) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-15 05:35:42.723457) =========
========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 14 secs) (at 2016-10-15 05:35:42.723726) =========
'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Fx-Team' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Fx-Team'
in dir C:\slave\test\. (timeout 3600 secs) (maxTime 7200 secs)
watching logfiles {}
argv: ['c:/mozilla-build/python27/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-096
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-096
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-096
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
05:35:42 INFO - MultiFileLogger online at 20161015 05:35:42 in C:\slave\test
05:35:42 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team
05:35:42 INFO - Dumping config to C:\slave\test\logs\localconfig.json.
05:35:42 INFO - {'append_to_log': False,
05:35:42 INFO - 'base_work_dir': 'C:\\slave\\test',
05:35:42 INFO - 'blob_upload_branch': 'Fx-Team',
05:35:42 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt',
05:35:42 INFO - 'branch': 'Fx-Team',
05:35:42 INFO - 'buildbot_json_path': 'buildprops.json',
05:35:42 INFO - 'config_files': ('talos/windows_config.py',),
05:35:42 INFO - 'default_actions': ('clobber',
05:35:42 INFO - 'read-buildbot-config',
05:35:42 INFO - 'download-and-extract',
05:35:42 INFO - 'populate-webroot',
05:35:42 INFO - 'create-virtualenv',
05:35:42 INFO - 'install',
05:35:42 INFO - 'run-tests'),
05:35:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
05:35:42 INFO - 'download_minidump_stackwalk': True,
05:35:42 INFO - 'download_symbols': 'ondemand',
05:35:42 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python',
05:35:42 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'),
05:35:42 INFO - 'hg': 'c:/mozilla-build/hg/hg',
05:35:42 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python',
05:35:42 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'),
05:35:42 INFO - 'python': 'c:/mozilla-build/python27/python.exe',
05:35:42 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe',
05:35:42 INFO - 'C:/mozilla-build/tooltool.py'),
05:35:42 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe',
05:35:42 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')},
05:35:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
05:35:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
05:35:42 INFO - 'installer_path': 'installer.exe',
05:35:42 INFO - 'log_level': 'info',
05:35:42 INFO - 'log_name': 'talos',
05:35:42 INFO - 'log_to_console': True,
05:35:42 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe',
05:35:42 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe',
05:35:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest',
05:35:42 INFO - 'opt_config_files': (),
05:35:42 INFO - 'pip_index': False,
05:35:42 INFO - 'sps_profile': False,
05:35:42 INFO - 'sps_profile_interval': 0,
05:35:42 INFO - 'suite': 'dromaeojs',
05:35:42 INFO - 'system_bits': '32',
05:35:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
05:35:42 INFO - 'title': 'T-W732-IX-096',
05:35:42 INFO - 'use_talos_json': True,
05:35:42 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'),
05:35:42 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv',
05:35:42 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll',
05:35:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
05:35:42 INFO - 'work_dir': 'build'}
05:35:42 INFO - [mozharness: 2016-10-15 12:35:42.942000Z] Running clobber step.
05:35:42 INFO - Running pre-action listener: _resource_record_pre_action
05:35:42 INFO - Running main action method: clobber
05:35:42 INFO - rmtree: C:\slave\test\build
05:35:42 INFO - Using _rmtree_windows ...
05:35:43 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1
05:37:17 INFO - Running post-action listener: _resource_record_post_action
05:37:17 INFO - [mozharness: 2016-10-15 12:37:17.999000Z] Finished clobber step (success)
05:37:17 INFO - [mozharness: 2016-10-15 12:37:17.999000Z] Running read-buildbot-config step.
05:37:17 INFO - Running pre-action listener: _resource_record_pre_action
05:37:18 INFO - Running main action method: read_buildbot_config
05:37:18 INFO - Using buildbot properties:
05:37:18 INFO - {
05:37:18 INFO - "project": "",
05:37:18 INFO - "product": "firefox",
05:37:18 INFO - "script_repo_revision": "production",
05:37:18 INFO - "builddir": "fx-team_win7_ix_test-dromaeojs",
05:37:18 INFO - "repository": "",
05:37:18 INFO - "buildername": "Windows 7 32-bit fx-team pgo talos dromaeojs",
05:37:18 INFO - "buildid": "20161015053455",
05:37:18 INFO - "stage_platform": "win32-pgo",
05:37:18 INFO - "basedir": "C:\\slave\\test",
05:37:18 INFO - "buildnumber": 229,
05:37:18 INFO - "platform": "win7_ix",
05:37:18 INFO - "master": "http://buildbot-master110.bb.releng.scl3.mozilla.com:8201/",
05:37:18 INFO - "slavebuilddir": "test",
05:37:18 INFO - "branch": "fx-team",
05:37:18 INFO - "revision": "44b01519b5668699f2c1a10d362445be59711d35",
05:37:18 INFO - "slavename": "t-w732-ix-096",
05:37:18 INFO - "builduid": "83108166a2b4455cac78f3c5965ee5c1",
05:37:18 INFO - "repo_path": "integration/fx-team"
05:37:18 INFO - }
05:37:18 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476527404/firefox-52.0a1.en-US.win32.test_packages.json.
05:37:18 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476527404/firefox-52.0a1.en-US.win32.zip.
05:37:18 INFO - Running post-action listener: _resource_record_post_action
05:37:18 INFO - [mozharness: 2016-10-15 12:37:18.002000Z] Finished read-buildbot-config step (success)
05:37:18 INFO - [mozharness: 2016-10-15 12:37:18.003000Z] Running download-and-extract step.
05:37:18 INFO - Running pre-action listener: _resource_record_pre_action
05:37:18 INFO - Running main action method: download_and_extract
05:37:18 INFO - mkdir: C:\slave\test\build\tests
05:37:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
05:37:18 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476527404/firefox-52.0a1.en-US.win32.test_packages.json
05:37:18 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476527404/firefox-52.0a1.en-US.win32.test_packages.json to C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json
05:37:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476527404/firefox-52.0a1.en-US.win32.test_packages.json', 'file_name': 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.test_packages.json'}, attempt #1
05:37:18 INFO - Downloaded 1218 bytes.
05:37:18 INFO - Reading from file C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json
05:37:18 INFO - Using the following test package requirements:
05:37:18 INFO - {u'common': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
05:37:18 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
05:37:18 INFO - u'firefox-52.0a1.en-US.win32.cppunittest.tests.zip'],
05:37:18 INFO - u'gtest': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
05:37:18 INFO - u'jittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
05:37:18 INFO - u'jsshell-win32.zip'],
05:37:18 INFO - u'mochitest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
05:37:18 INFO - u'firefox-52.0a1.en-US.win32.mochitest.tests.zip'],
05:37:18 INFO - u'mozbase': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
05:37:18 INFO - u'reftest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
05:37:18 INFO - u'firefox-52.0a1.en-US.win32.reftest.tests.zip'],
05:37:18 INFO - u'talos': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
05:37:18 INFO - u'firefox-52.0a1.en-US.win32.talos.tests.zip'],
05:37:18 INFO - u'web-platform': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
05:37:18 INFO - u'firefox-52.0a1.en-US.win32.web-platform.tests.zip'],
05:37:18 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
05:37:18 INFO - u'firefox-52.0a1.en-US.win32.xpcshell.tests.zip']}
05:37:18 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip'] for test suite category: common
05:37:18 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476527404/firefox-52.0a1.en-US.win32.common.tests.zip
05:37:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476527404/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1
05:37:18 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476527404/firefox-52.0a1.en-US.win32.common.tests.zip into memory
05:37:18 INFO - Http code: 200
05:37:18 INFO - via: 1.1 30bb04916f91d64c600e15c15000042d.cloudfront.net (CloudFront)
05:37:18 INFO - x-amz-cf-id: g5fd-9fHOX0pmIYb5FZB36nznpiYFtbsSs1msz2xCFvuxVMGwLhFiA==
05:37:18 INFO - x-amz-replication-status: COMPLETED
05:37:18 INFO - x-amz-version-id: 4K_zPiMLvnzuUKSrwCf40fRE4rMxGPYZ
05:37:19 INFO - Expected file size: 17275111
05:37:19 INFO - Obtained file size: 17275111
05:37:45 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip', u'firefox-52.0a1.en-US.win32.talos.tests.zip'] for test suite category: talos
05:37:45 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476527404/firefox-52.0a1.en-US.win32.common.tests.zip
05:37:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476527404/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1
05:37:45 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476527404/firefox-52.0a1.en-US.win32.common.tests.zip into memory
05:37:45 INFO - Http code: 200
05:37:45 INFO - via: 1.1 939ea9d62ec616846d41b16cea0dc65b.cloudfront.net (CloudFront)
05:37:45 INFO - x-amz-cf-id: TnNYuM-rfrSOhecMemR2xcQl3VZfNxWWRGOH-K1j3IoYxLKWkh44tA==
05:37:45 INFO - x-amz-replication-status: COMPLETED
05:37:45 INFO - x-amz-version-id: 4K_zPiMLvnzuUKSrwCf40fRE4rMxGPYZ
05:37:45 INFO - Expected file size: 17275111
05:37:45 INFO - Obtained file size: 17275111
05:37:55 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476527404/firefox-52.0a1.en-US.win32.talos.tests.zip
05:37:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476527404/firefox-52.0a1.en-US.win32.talos.tests.zip'}, attempt #1
05:37:55 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476527404/firefox-52.0a1.en-US.win32.talos.tests.zip into memory
05:37:55 INFO - Http code: 200
05:37:55 INFO - via: 1.1 95da1452a75435200220a7075ca3893f.cloudfront.net (CloudFront)
05:37:55 INFO - x-amz-cf-id: xquZ4q9KfhMNfzQnER2tfzAZ9CuOPb-pHKjPChUZ1HlaMGqWSK1H5g==
05:37:55 INFO - x-amz-replication-status: COMPLETED
05:37:55 INFO - x-amz-version-id: FEuuEwtJxoSg3OmBXkf24VnD5exs.kKq
05:37:56 INFO - Expected file size: 14409028
05:37:56 INFO - Obtained file size: 14409028
05:38:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
05:38:02 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476527404/firefox-52.0a1.en-US.win32.zip
05:38:02 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476527404/firefox-52.0a1.en-US.win32.zip to C:\slave\test\build\firefox-52.0a1.en-US.win32.zip
05:38:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476527404/firefox-52.0a1.en-US.win32.zip', 'file_name': 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.zip'}, attempt #1
05:38:05 INFO - Downloaded 54314603 bytes.
05:38:05 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476527404/firefox-52.0a1.en-US.win32.zip
05:38:05 INFO - mkdir: C:\slave\test\properties
05:38:05 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url
05:38:05 INFO - Writing to file C:\slave\test\properties\build_url
05:38:05 INFO - Contents:
05:38:05 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476527404/firefox-52.0a1.en-US.win32.zip
05:38:05 INFO - Running post-action listener: _resource_record_post_action
05:38:05 INFO - Running post-action listener: set_extra_try_arguments
05:38:05 INFO - [mozharness: 2016-10-15 12:38:05.511000Z] Finished download-and-extract step (success)
05:38:05 INFO - [mozharness: 2016-10-15 12:38:05.511000Z] Running populate-webroot step.
05:38:05 INFO - Running pre-action listener: _resource_record_pre_action
05:38:05 INFO - Running main action method: populate_webroot
05:38:05 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']},
05:38:05 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']},
05:38:05 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome',
05:38:05 INFO - u'--tppagecycles',
05:38:05 INFO - u'7'],
05:38:05 INFO - u'tests': [u'tsvgm']}},
05:38:05 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'],
05:38:05 INFO - u'tests': [u'tresize', u'tcanvasmark']},
05:38:05 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']},
05:38:05 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'],
05:38:05 INFO - u'tests': [u'dromaeo_css', u'kraken']},
05:38:05 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']},
05:38:05 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
05:38:05 INFO - u'talos_options': [u'--disable-e10s'],
05:38:05 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
05:38:05 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
05:38:05 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
05:38:05 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
05:38:05 INFO - u'talos_options': [u'--disable-e10s'],
05:38:05 INFO - u'tests': [u'damp', u'tps']},
05:38:05 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
05:38:05 INFO - u'tests': [u'damp', u'tps']},
05:38:05 INFO - u'g3': {u'talos_options': [u'--disable-e10s'],
05:38:05 INFO - u'tests': [u'dromaeo_dom']},
05:38:05 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']},
05:38:05 INFO - u'g4': {u'talos_options': [u'--disable-e10s'],
05:38:05 INFO - u'tests': [u'basic_compositor_video']},
05:38:05 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']},
05:38:05 INFO - u'other': {u'talos_options': [u'--disable-e10s'],
05:38:05 INFO - u'tests': [u'a11yr',
05:38:05 INFO - u'ts_paint',
05:38:05 INFO - u'tpaint',
05:38:05 INFO - u'sessionrestore',
05:38:05 INFO - u'sessionrestore_no_auto_restore',
05:38:05 INFO - u'tabpaint']},
05:38:05 INFO - u'other-e10s': {u'tests': [u'a11yr',
05:38:05 INFO - u'ts_paint',
05:38:05 INFO - u'tpaint',
05:38:05 INFO - u'sessionrestore',
05:38:05 INFO - u'sessionrestore_no_auto_restore',
05:38:05 INFO - u'tabpaint']},
05:38:05 INFO - u'other-e10s_l64': {u'tests': [u'a11yr',
05:38:05 INFO - u'ts_paint',
05:38:05 INFO - u'tpaint',
05:38:05 INFO - u'sessionrestore',
05:38:05 INFO - u'sessionrestore_no_auto_restore',
05:38:05 INFO - u'tabpaint']},
05:38:05 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr',
05:38:05 INFO - u'ts_paint',
05:38:05 INFO - u'tpaint',
05:38:05 INFO - u'sessionrestore',
05:38:05 INFO - u'sessionrestore_no_auto_restore',
05:38:05 INFO - u'tabpaint']},
05:38:05 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'],
05:38:05 INFO - u'tests': [u'a11yr',
05:38:05 INFO - u'ts_paint',
05:38:05 INFO - u'tpaint',
05:38:05 INFO - u'sessionrestore',
05:38:05 INFO - u'sessionrestore_no_auto_restore',
05:38:05 INFO - u'tabpaint']},
05:38:05 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'],
05:38:05 INFO - u'tests': [u'a11yr',
05:38:05 INFO - u'ts_paint',
05:38:05 INFO - u'tpaint',
05:38:05 INFO - u'sessionrestore',
05:38:05 INFO - u'sessionrestore_no_auto_restore',
05:38:05 INFO - u'tabpaint']},
05:38:05 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'],
05:38:05 INFO - u'tests': [u'tsvgx',
05:38:05 INFO - u'tsvgr_opacity',
05:38:05 INFO - u'tart',
05:38:05 INFO - u'tscrollx',
05:38:05 INFO - u'cart']},
05:38:05 INFO - u'svgr-e10s': {u'tests': [u'tsvgx',
05:38:05 INFO - u'tsvgr_opacity',
05:38:05 INFO - u'tart',
05:38:05 INFO - u'tscrollx',
05:38:05 INFO - u'cart']},
05:38:05 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
05:38:05 INFO - u'talos_options': [u'--disable-e10s'],
05:38:05 INFO - u'tests': [u'tp5o']},
05:38:05 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
05:38:05 INFO - u'tests': [u'tp5o']},
05:38:05 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
05:38:05 INFO - u'talos_options': [u'--disable-e10s',
05:38:05 INFO - u'--xperf_path',
05:38:05 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
05:38:05 INFO - u'tests': [u'tp5n']},
05:38:05 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
05:38:05 INFO - u'talos_options': [u'--xperf_path',
05:38:05 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
05:38:05 INFO - u'tests': [u'tp5n']}},
05:38:05 INFO - u'talos.zip': {u'path': u'',
05:38:05 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}}
05:38:05 INFO - Running post-action listener: _resource_record_post_action
05:38:05 INFO - [mozharness: 2016-10-15 12:38:05.611000Z] Finished populate-webroot step (success)
05:38:05 INFO - [mozharness: 2016-10-15 12:38:05.612000Z] Running create-virtualenv step.
05:38:05 INFO - Running pre-action listener: _resource_record_pre_action
05:38:05 INFO - Running main action method: create_virtualenv
05:38:05 INFO - Creating virtualenv C:\slave\test\build/venv
05:38:05 INFO - mkdir: C:\slave\test\build\venv\Scripts
05:38:06 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll
05:38:06 WARNING - Can't copy c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll: [Errno 2] No such file or directory: 'c:/mozilla-build/python27/python27.dll'!
05:38:06 INFO - Running command: ['c:/mozilla-build/python27/python.exe', 'c:/mozilla-build/buildbotve/virtualenv.py', '--no-site-packages', '--distribute', 'C:\\slave\\test\\build/venv'] in C:\slave\test\build
05:38:06 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv
05:38:16 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe
05:38:19 INFO - Installing distribute......................................................................................................................................................................................done.
05:38:21 INFO - Return code: 0
05:38:21 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version']
05:38:21 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version
05:38:21 INFO - Reading from file tmpfile_stdout
05:38:21 INFO - Output received:
05:38:21 INFO - pip 0.8.2 from c:\slave\test\build\venv\lib\site-packages\pip-0.8.2-py2.7.egg (python 2.7)
05:38:21 INFO - Using _rmtree_windows ...
05:38:21 INFO - Using _rmtree_windows ...
05:38:21 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv
05:38:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
05:38:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
05:38:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
05:38:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
05:38:21 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01918640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01952E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01892B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0196CE90>, '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 0x0136A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019D8520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-096', 'USERDOMAIN': 'T-W732-IX-096', '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-096', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
05:38:21 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in C:\slave\test\build
05:38:21 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5
05:38:21 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
05:38:21 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
05:38:21 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
05:38:21 INFO - 'COMPUTERNAME': 'T-W732-IX-096',
05:38:21 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
05:38:21 INFO - 'CYGWINBASE': 'C:\\cygwin',
05:38:21 INFO - 'DCLOCATION': 'SCL3',
05:38:21 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
05:38:21 INFO - 'FP_NO_HOST_CHECK': 'NO',
05:38:21 INFO - 'HOMEDRIVE': 'C:',
05:38:21 INFO - 'HOMEPATH': '\\Users\\cltbld',
05:38:21 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
05:38:21 INFO - 'KTS_VERSION': '1.19c',
05:38:21 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
05:38:21 INFO - 'LOGONSERVER': '\\\\T-W732-IX-096',
05:38:21 INFO - 'MONDIR': 'C:\\Monitor_config\\',
05:38:21 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
05:38:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
05:38:21 INFO - 'MOZ_NO_REMOTE': '1',
05:38:21 INFO - 'NO_EM_RESTART': '1',
05:38:21 INFO - 'NUMBER_OF_PROCESSORS': '8',
05:38:21 INFO - 'OS': 'Windows_NT',
05:38:21 INFO - 'OURDRIVE': 'C:',
05:38:21 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
05:38:21 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
05:38:21 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
05:38:21 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
05:38:21 INFO - 'PROCESSOR_LEVEL': '6',
05:38:21 INFO - 'PROCESSOR_REVISION': '1e05',
05:38:21 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
05:38:21 INFO - 'PROGRAMFILES': 'C:\\Program Files',
05:38:21 INFO - 'PROMPT': '$P$G',
05:38:21 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
05:38:21 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
05:38:21 INFO - 'PUBLIC': 'C:\\Users\\Public',
05:38:21 INFO - 'PWD': 'C:\\slave\\test',
05:38:21 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
05:38:21 INFO - 'SLAVEDIR': 'C:\\slave\\',
05:38:21 INFO - 'SYSTEMDRIVE': 'C:',
05:38:21 INFO - 'SYSTEMROOT': 'C:\\windows',
05:38:21 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
05:38:21 INFO - 'TEST1': 'testie',
05:38:21 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
05:38:21 INFO - 'USERDOMAIN': 'T-W732-IX-096',
05:38:21 INFO - 'USERNAME': 'cltbld',
05:38:21 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
05:38:21 INFO - 'WINDIR': 'C:\\windows',
05:38:21 INFO - 'WINDOWS_TRACING_FLAGS': '3',
05:38:21 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
05:38:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
05:38:22 INFO - Ignoring indexes: http://pypi.python.org/simple/
05:38:22 INFO - Downloading/unpacking pip>=1.5
05:38:26 INFO - Running setup.py egg_info for package pip
05:38:26 INFO - warning: no files found matching 'pip\cacert.pem'
05:38:26 INFO - warning: no files found matching '*.html' under directory 'docs'
05:38:26 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
05:38:26 INFO - no previously-included directories found matching 'docs\_build\_sources'
05:38:26 INFO - Installing collected packages: pip
05:38:26 INFO - Found existing installation: pip 0.8.2
05:38:26 INFO - Uninstalling pip:
05:38:26 INFO - Successfully uninstalled pip
05:38:26 INFO - Running setup.py install for pip
05:38:26 INFO - warning: no files found matching 'pip\cacert.pem'
05:38:26 INFO - warning: no files found matching '*.html' under directory 'docs'
05:38:26 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
05:38:26 INFO - no previously-included directories found matching 'docs\_build\_sources'
05:38:26 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts
05:38:26 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts
05:38:26 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts
05:38:26 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts
05:38:26 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts
05:38:26 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts
05:38:26 INFO - Successfully installed pip
05:38:26 INFO - Cleaning up...
05:38:26 INFO - Return code: 0
05:38:26 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv
05:38:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
05:38:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
05:38:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
05:38:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
05:38:26 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 0x01918640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01952E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01892B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0196CE90>, '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 0x0136A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019D8520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-096', 'USERDOMAIN': 'T-W732-IX-096', '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-096', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
05:38:26 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in C:\slave\test\build
05:38:26 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1
05:38:26 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
05:38:26 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
05:38:26 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
05:38:26 INFO - 'COMPUTERNAME': 'T-W732-IX-096',
05:38:26 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
05:38:26 INFO - 'CYGWINBASE': 'C:\\cygwin',
05:38:26 INFO - 'DCLOCATION': 'SCL3',
05:38:26 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
05:38:26 INFO - 'FP_NO_HOST_CHECK': 'NO',
05:38:26 INFO - 'HOMEDRIVE': 'C:',
05:38:26 INFO - 'HOMEPATH': '\\Users\\cltbld',
05:38:26 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
05:38:26 INFO - 'KTS_VERSION': '1.19c',
05:38:26 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
05:38:26 INFO - 'LOGONSERVER': '\\\\T-W732-IX-096',
05:38:26 INFO - 'MONDIR': 'C:\\Monitor_config\\',
05:38:26 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
05:38:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
05:38:26 INFO - 'MOZ_NO_REMOTE': '1',
05:38:26 INFO - 'NO_EM_RESTART': '1',
05:38:26 INFO - 'NUMBER_OF_PROCESSORS': '8',
05:38:26 INFO - 'OS': 'Windows_NT',
05:38:26 INFO - 'OURDRIVE': 'C:',
05:38:26 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
05:38:26 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
05:38:26 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
05:38:26 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
05:38:26 INFO - 'PROCESSOR_LEVEL': '6',
05:38:26 INFO - 'PROCESSOR_REVISION': '1e05',
05:38:26 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
05:38:26 INFO - 'PROGRAMFILES': 'C:\\Program Files',
05:38:26 INFO - 'PROMPT': '$P$G',
05:38:26 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
05:38:26 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
05:38:26 INFO - 'PUBLIC': 'C:\\Users\\Public',
05:38:26 INFO - 'PWD': 'C:\\slave\\test',
05:38:26 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
05:38:26 INFO - 'SLAVEDIR': 'C:\\slave\\',
05:38:26 INFO - 'SYSTEMDRIVE': 'C:',
05:38:26 INFO - 'SYSTEMROOT': 'C:\\windows',
05:38:26 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
05:38:26 INFO - 'TEST1': 'testie',
05:38:26 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
05:38:26 INFO - 'USERDOMAIN': 'T-W732-IX-096',
05:38:26 INFO - 'USERNAME': 'cltbld',
05:38:26 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
05:38:26 INFO - 'WINDIR': 'C:\\windows',
05:38:26 INFO - 'WINDOWS_TRACING_FLAGS': '3',
05:38:26 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
05:38:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
05:38:28 INFO - Ignoring indexes: https://pypi.python.org/simple/
05:38:28 INFO - Downloading/unpacking psutil>=3.1.1
05:38:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
05:38:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
05:38:29 INFO - Installing collected packages: psutil
05:38:29 INFO - Successfully installed psutil
05:38:29 INFO - Cleaning up...
05:38:29 INFO - Return code: 0
05:38:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv
05:38:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
05:38:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
05:38:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
05:38:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
05:38:29 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01918640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01952E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01892B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0196CE90>, '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 0x0136A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019D8520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-096', 'USERDOMAIN': 'T-W732-IX-096', '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-096', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
05:38:29 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in C:\slave\test\build
05:38:29 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3
05:38:29 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
05:38:29 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
05:38:29 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
05:38:29 INFO - 'COMPUTERNAME': 'T-W732-IX-096',
05:38:29 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
05:38:29 INFO - 'CYGWINBASE': 'C:\\cygwin',
05:38:29 INFO - 'DCLOCATION': 'SCL3',
05:38:29 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
05:38:29 INFO - 'FP_NO_HOST_CHECK': 'NO',
05:38:29 INFO - 'HOMEDRIVE': 'C:',
05:38:29 INFO - 'HOMEPATH': '\\Users\\cltbld',
05:38:29 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
05:38:29 INFO - 'KTS_VERSION': '1.19c',
05:38:29 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
05:38:29 INFO - 'LOGONSERVER': '\\\\T-W732-IX-096',
05:38:29 INFO - 'MONDIR': 'C:\\Monitor_config\\',
05:38:29 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
05:38:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
05:38:29 INFO - 'MOZ_NO_REMOTE': '1',
05:38:29 INFO - 'NO_EM_RESTART': '1',
05:38:29 INFO - 'NUMBER_OF_PROCESSORS': '8',
05:38:29 INFO - 'OS': 'Windows_NT',
05:38:29 INFO - 'OURDRIVE': 'C:',
05:38:29 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
05:38:29 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
05:38:29 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
05:38:29 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
05:38:29 INFO - 'PROCESSOR_LEVEL': '6',
05:38:29 INFO - 'PROCESSOR_REVISION': '1e05',
05:38:29 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
05:38:29 INFO - 'PROGRAMFILES': 'C:\\Program Files',
05:38:29 INFO - 'PROMPT': '$P$G',
05:38:29 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
05:38:29 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
05:38:29 INFO - 'PUBLIC': 'C:\\Users\\Public',
05:38:29 INFO - 'PWD': 'C:\\slave\\test',
05:38:29 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
05:38:29 INFO - 'SLAVEDIR': 'C:\\slave\\',
05:38:29 INFO - 'SYSTEMDRIVE': 'C:',
05:38:29 INFO - 'SYSTEMROOT': 'C:\\windows',
05:38:29 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
05:38:29 INFO - 'TEST1': 'testie',
05:38:29 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
05:38:29 INFO - 'USERDOMAIN': 'T-W732-IX-096',
05:38:29 INFO - 'USERNAME': 'cltbld',
05:38:29 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
05:38:29 INFO - 'WINDIR': 'C:\\windows',
05:38:29 INFO - 'WINDOWS_TRACING_FLAGS': '3',
05:38:29 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
05:38:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
05:38:29 INFO - Ignoring indexes: https://pypi.python.org/simple/
05:38:29 INFO - Downloading/unpacking mozsystemmonitor==0.3
05:38:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
05:38:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
05:38:30 INFO - Downloading mozsystemmonitor-0.3.tar.gz
05:38:30 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor
05:38:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in c:\slave\test\build\venv\lib\site-packages (from mozsystemmonitor==0.3)
05:38:31 INFO - Installing collected packages: mozsystemmonitor
05:38:31 INFO - Running setup.py install for mozsystemmonitor
05:38:31 INFO - Successfully installed mozsystemmonitor
05:38:31 INFO - Cleaning up...
05:38:31 INFO - Return code: 0
05:38:31 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv
05:38: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')]}
05:38:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
05:38: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')]}
05:38:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
05:38: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', '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 0x01918640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01952E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01892B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0196CE90>, '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 0x0136A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019D8520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-096', 'USERDOMAIN': 'T-W732-IX-096', '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-096', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
05:38: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', 'jsonschema==2.5.1'] in C:\slave\test\build
05:38: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 jsonschema==2.5.1
05:38:31 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
05:38:31 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
05:38:31 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
05:38:31 INFO - 'COMPUTERNAME': 'T-W732-IX-096',
05:38:31 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
05:38:31 INFO - 'CYGWINBASE': 'C:\\cygwin',
05:38:31 INFO - 'DCLOCATION': 'SCL3',
05:38:31 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
05:38:31 INFO - 'FP_NO_HOST_CHECK': 'NO',
05:38:31 INFO - 'HOMEDRIVE': 'C:',
05:38:31 INFO - 'HOMEPATH': '\\Users\\cltbld',
05:38:31 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
05:38:31 INFO - 'KTS_VERSION': '1.19c',
05:38:31 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
05:38:31 INFO - 'LOGONSERVER': '\\\\T-W732-IX-096',
05:38:31 INFO - 'MONDIR': 'C:\\Monitor_config\\',
05:38:31 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
05:38:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
05:38:31 INFO - 'MOZ_NO_REMOTE': '1',
05:38:31 INFO - 'NO_EM_RESTART': '1',
05:38:31 INFO - 'NUMBER_OF_PROCESSORS': '8',
05:38:31 INFO - 'OS': 'Windows_NT',
05:38:31 INFO - 'OURDRIVE': 'C:',
05:38: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;',
05:38:31 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
05:38:31 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
05:38:31 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
05:38:31 INFO - 'PROCESSOR_LEVEL': '6',
05:38:31 INFO - 'PROCESSOR_REVISION': '1e05',
05:38:31 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
05:38:31 INFO - 'PROGRAMFILES': 'C:\\Program Files',
05:38:31 INFO - 'PROMPT': '$P$G',
05:38:31 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
05:38:31 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
05:38:31 INFO - 'PUBLIC': 'C:\\Users\\Public',
05:38:31 INFO - 'PWD': 'C:\\slave\\test',
05:38:31 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
05:38:31 INFO - 'SLAVEDIR': 'C:\\slave\\',
05:38:31 INFO - 'SYSTEMDRIVE': 'C:',
05:38:31 INFO - 'SYSTEMROOT': 'C:\\windows',
05:38:31 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
05:38:31 INFO - 'TEST1': 'testie',
05:38:31 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
05:38:31 INFO - 'USERDOMAIN': 'T-W732-IX-096',
05:38:31 INFO - 'USERNAME': 'cltbld',
05:38:31 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
05:38:31 INFO - 'WINDIR': 'C:\\windows',
05:38:31 INFO - 'WINDOWS_TRACING_FLAGS': '3',
05:38:31 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
05:38:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
05:38:31 INFO - Ignoring indexes: https://pypi.python.org/simple/
05:38:31 INFO - Downloading/unpacking jsonschema==2.5.1
05:38:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
05:38:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
05:38:32 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl
05:38:32 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1)
05:38:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
05:38:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
05:38:32 INFO - Downloading functools32-3.2.3-2.tar.gz
05:38:32 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32
05:38:33 INFO - warning: no files found matching '*.txt'
05:38:33 INFO - no previously-included directories found matching 'build'
05:38:33 INFO - no previously-included directories found matching 'dist'
05:38:33 INFO - no previously-included directories found matching '.git*'
05:38:33 INFO - Installing collected packages: jsonschema, functools32
05:38:33 INFO - Running setup.py install for functools32
05:38:33 INFO - warning: no files found matching '*.txt'
05:38:33 INFO - no previously-included directories found matching 'build'
05:38:33 INFO - no previously-included directories found matching 'dist'
05:38:33 INFO - no previously-included directories found matching '.git*'
05:38:33 INFO - Successfully installed jsonschema functools32
05:38:33 INFO - Cleaning up...
05:38:33 INFO - Return code: 0
05:38:33 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv
05:38:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
05:38:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
05:38:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
05:38:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
05:38:33 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 0x01918640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01952E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01892B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0196CE90>, '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 0x0136A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019D8520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-096', 'USERDOMAIN': 'T-W732-IX-096', '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-096', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
05:38:33 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in C:\slave\test\build
05:38:33 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2
05:38:33 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
05:38:33 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
05:38:33 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
05:38:33 INFO - 'COMPUTERNAME': 'T-W732-IX-096',
05:38:33 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
05:38:33 INFO - 'CYGWINBASE': 'C:\\cygwin',
05:38:33 INFO - 'DCLOCATION': 'SCL3',
05:38:33 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
05:38:33 INFO - 'FP_NO_HOST_CHECK': 'NO',
05:38:33 INFO - 'HOMEDRIVE': 'C:',
05:38:33 INFO - 'HOMEPATH': '\\Users\\cltbld',
05:38:33 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
05:38:33 INFO - 'KTS_VERSION': '1.19c',
05:38:33 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
05:38:33 INFO - 'LOGONSERVER': '\\\\T-W732-IX-096',
05:38:33 INFO - 'MONDIR': 'C:\\Monitor_config\\',
05:38:33 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
05:38:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
05:38:33 INFO - 'MOZ_NO_REMOTE': '1',
05:38:33 INFO - 'NO_EM_RESTART': '1',
05:38:33 INFO - 'NUMBER_OF_PROCESSORS': '8',
05:38:33 INFO - 'OS': 'Windows_NT',
05:38:33 INFO - 'OURDRIVE': 'C:',
05:38:33 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
05:38:33 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
05:38:33 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
05:38:33 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
05:38:33 INFO - 'PROCESSOR_LEVEL': '6',
05:38:33 INFO - 'PROCESSOR_REVISION': '1e05',
05:38:33 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
05:38:33 INFO - 'PROGRAMFILES': 'C:\\Program Files',
05:38:33 INFO - 'PROMPT': '$P$G',
05:38:33 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
05:38:33 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
05:38:33 INFO - 'PUBLIC': 'C:\\Users\\Public',
05:38:33 INFO - 'PWD': 'C:\\slave\\test',
05:38:33 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
05:38:33 INFO - 'SLAVEDIR': 'C:\\slave\\',
05:38:33 INFO - 'SYSTEMDRIVE': 'C:',
05:38:33 INFO - 'SYSTEMROOT': 'C:\\windows',
05:38:33 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
05:38:33 INFO - 'TEST1': 'testie',
05:38:33 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
05:38:33 INFO - 'USERDOMAIN': 'T-W732-IX-096',
05:38:33 INFO - 'USERNAME': 'cltbld',
05:38:33 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
05:38:33 INFO - 'WINDIR': 'C:\\windows',
05:38:33 INFO - 'WINDOWS_TRACING_FLAGS': '3',
05:38:33 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
05:38:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
05:38:34 INFO - Ignoring indexes: https://pypi.python.org/simple/
05:38:34 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages
05:38:34 INFO - Cleaning up...
05:38:34 INFO - Return code: 0
05:38:34 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv
05:38:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
05:38:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
05:38:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
05:38:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
05:38:34 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 0x01918640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01952E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01892B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0196CE90>, '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 0x0136A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019D8520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-096', 'USERDOMAIN': 'T-W732-IX-096', '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-096', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
05:38:34 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in C:\slave\test\build
05:38:34 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4
05:38:34 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
05:38:34 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
05:38:34 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
05:38:34 INFO - 'COMPUTERNAME': 'T-W732-IX-096',
05:38:34 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
05:38:34 INFO - 'CYGWINBASE': 'C:\\cygwin',
05:38:34 INFO - 'DCLOCATION': 'SCL3',
05:38:34 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
05:38:34 INFO - 'FP_NO_HOST_CHECK': 'NO',
05:38:34 INFO - 'HOMEDRIVE': 'C:',
05:38:34 INFO - 'HOMEPATH': '\\Users\\cltbld',
05:38:34 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
05:38:34 INFO - 'KTS_VERSION': '1.19c',
05:38:34 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
05:38:34 INFO - 'LOGONSERVER': '\\\\T-W732-IX-096',
05:38:34 INFO - 'MONDIR': 'C:\\Monitor_config\\',
05:38:34 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
05:38:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
05:38:34 INFO - 'MOZ_NO_REMOTE': '1',
05:38:34 INFO - 'NO_EM_RESTART': '1',
05:38:34 INFO - 'NUMBER_OF_PROCESSORS': '8',
05:38:34 INFO - 'OS': 'Windows_NT',
05:38:34 INFO - 'OURDRIVE': 'C:',
05:38:34 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
05:38:34 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
05:38:34 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
05:38:34 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
05:38:34 INFO - 'PROCESSOR_LEVEL': '6',
05:38:34 INFO - 'PROCESSOR_REVISION': '1e05',
05:38:34 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
05:38:34 INFO - 'PROGRAMFILES': 'C:\\Program Files',
05:38:34 INFO - 'PROMPT': '$P$G',
05:38:34 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
05:38:34 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
05:38:34 INFO - 'PUBLIC': 'C:\\Users\\Public',
05:38:34 INFO - 'PWD': 'C:\\slave\\test',
05:38:34 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
05:38:34 INFO - 'SLAVEDIR': 'C:\\slave\\',
05:38:34 INFO - 'SYSTEMDRIVE': 'C:',
05:38:34 INFO - 'SYSTEMROOT': 'C:\\windows',
05:38:34 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
05:38:34 INFO - 'TEST1': 'testie',
05:38:34 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
05:38:34 INFO - 'USERDOMAIN': 'T-W732-IX-096',
05:38:34 INFO - 'USERNAME': 'cltbld',
05:38:34 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
05:38:34 INFO - 'WINDIR': 'C:\\windows',
05:38:34 INFO - 'WINDOWS_TRACING_FLAGS': '3',
05:38:34 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
05:38:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
05:38:34 INFO - Ignoring indexes: https://pypi.python.org/simple/
05:38:34 INFO - Downloading/unpacking blobuploader==1.2.4
05:38:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
05:38:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
05:38:35 INFO - Downloading blobuploader-1.2.4.tar.gz
05:38:35 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader
05:38:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4)
05:38:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
05:38:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
05:38:36 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests
05:38:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4)
05:38:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
05:38:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
05:38:36 INFO - Downloading docopt-0.6.1.tar.gz
05:38:36 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt
05:38:36 INFO - Installing collected packages: blobuploader, requests, docopt
05:38:36 INFO - Running setup.py install for blobuploader
05:38:37 INFO - Running setup.py install for requests
05:38:37 INFO - Running setup.py install for docopt
05:38:37 INFO - Successfully installed blobuploader requests docopt
05:38:37 INFO - Cleaning up...
05:38:39 INFO - Return code: 0
05:38:39 INFO - Installing None into virtualenv C:\slave\test\build/venv
05:38: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')]}
05:38:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
05:38: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')]}
05:38:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
05:38:39 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 0x01918640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01952E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01892B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0196CE90>, '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 0x0136A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019D8520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-096', 'USERDOMAIN': 'T-W732-IX-096', '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-096', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
05:38:39 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--no-deps', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test\build\tests\config
05:38:39 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --no-deps --timeout 120 -r C:\slave\test\build\tests\config\mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub
05:38:39 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
05:38:39 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
05:38:39 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
05:38:39 INFO - 'COMPUTERNAME': 'T-W732-IX-096',
05:38:39 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
05:38:39 INFO - 'CYGWINBASE': 'C:\\cygwin',
05:38:39 INFO - 'DCLOCATION': 'SCL3',
05:38:39 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
05:38:39 INFO - 'FP_NO_HOST_CHECK': 'NO',
05:38:39 INFO - 'HOMEDRIVE': 'C:',
05:38:39 INFO - 'HOMEPATH': '\\Users\\cltbld',
05:38:39 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
05:38:39 INFO - 'KTS_VERSION': '1.19c',
05:38:39 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
05:38:39 INFO - 'LOGONSERVER': '\\\\T-W732-IX-096',
05:38:39 INFO - 'MONDIR': 'C:\\Monitor_config\\',
05:38:39 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
05:38:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
05:38:39 INFO - 'MOZ_NO_REMOTE': '1',
05:38:39 INFO - 'NO_EM_RESTART': '1',
05:38:39 INFO - 'NUMBER_OF_PROCESSORS': '8',
05:38:39 INFO - 'OS': 'Windows_NT',
05:38:39 INFO - 'OURDRIVE': 'C:',
05:38: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;',
05:38:39 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
05:38:39 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
05:38:39 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
05:38:39 INFO - 'PROCESSOR_LEVEL': '6',
05:38:39 INFO - 'PROCESSOR_REVISION': '1e05',
05:38:39 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
05:38:39 INFO - 'PROGRAMFILES': 'C:\\Program Files',
05:38:39 INFO - 'PROMPT': '$P$G',
05:38:39 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
05:38:39 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
05:38:39 INFO - 'PUBLIC': 'C:\\Users\\Public',
05:38:39 INFO - 'PWD': 'C:\\slave\\test',
05:38:39 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
05:38:39 INFO - 'SLAVEDIR': 'C:\\slave\\',
05:38:39 INFO - 'SYSTEMDRIVE': 'C:',
05:38:39 INFO - 'SYSTEMROOT': 'C:\\windows',
05:38:39 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
05:38:39 INFO - 'TEST1': 'testie',
05:38:39 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
05:38:39 INFO - 'USERDOMAIN': 'T-W732-IX-096',
05:38:39 INFO - 'USERNAME': 'cltbld',
05:38:39 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
05:38:39 INFO - 'WINDIR': 'C:\\windows',
05:38:39 INFO - 'WINDOWS_TRACING_FLAGS': '3',
05:38:39 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
05:38:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
05:38:39 INFO - Ignoring indexes: https://pypi.python.org/simple/
05:38:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
05:38:40 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ppvcfh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
05:38:40 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
05:38:40 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vg2imm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
05:38:40 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
05:38:40 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-8yxqra-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
05:38:40 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
05:38:40 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-typc0g-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
05:38:40 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
05:38:40 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jujztp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
05:38:40 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
05:38:40 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-k3uflw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
05:38:40 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
05:38:41 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-nckwyc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
05:38:41 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
05:38:41 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-miusrg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
05:38:41 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
05:38:41 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qo7hq4-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
05:38:41 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
05:38:41 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-1twavw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
05:38:41 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
05:38:41 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-uagmtf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
05:38:41 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
05:38:41 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lbkyam-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
05:38:41 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
05:38:41 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ajb47c-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
05:38:42 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
05:38:42 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-7kky81-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
05:38:42 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
05:38:42 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kygyza-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
05:38:42 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
05:38:42 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wenxgy-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
05:38:42 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
05:38:42 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-srqftb-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
05:38:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
05:38:42 INFO - Running setup.py install for manifestparser
05:38:42 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts
05:38:42 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts
05:38:43 INFO - Running setup.py install for mozcrash
05:38:43 INFO - Running setup.py install for mozdebug
05:38:43 INFO - Running setup.py install for mozdevice
05:38:44 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts
05:38:44 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts
05:38:44 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts
05:38:44 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts
05:38:44 INFO - Running setup.py install for mozfile
05:38:44 INFO - Running setup.py install for mozhttpd
05:38:45 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts
05:38:45 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts
05:38:45 INFO - Running setup.py install for mozinfo
05:38:45 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts
05:38:45 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts
05:38:45 INFO - Running setup.py install for mozInstall
05:38:45 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts
05:38:45 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts
05:38:45 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts
05:38:45 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts
05:38:45 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts
05:38:45 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts
05:38:45 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts
05:38:45 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts
05:38:45 INFO - Running setup.py install for mozleak
05:38:46 INFO - Running setup.py install for mozlog
05:38:46 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts
05:38:46 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts
05:38:47 INFO - Running setup.py install for moznetwork
05:38:47 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts
05:38:47 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts
05:38:47 INFO - Running setup.py install for mozprocess
05:38:48 INFO - Running setup.py install for mozprofile
05:38:48 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts
05:38:48 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts
05:38:48 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts
05:38:48 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts
05:38:48 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts
05:38:48 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts
05:38:48 INFO - Running setup.py install for mozrunner
05:38:48 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts
05:38:48 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts
05:38:49 INFO - Running setup.py install for mozscreenshot
05:38:49 INFO - Running setup.py install for moztest
05:38:50 INFO - Running setup.py install for mozversion
05:38:50 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts
05:38:50 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts
05:38:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion
05:38:50 INFO - Cleaning up...
05:38:50 INFO - Return code: 0
05:38:50 INFO - Installing None into virtualenv C:\slave\test\build/venv
05:38:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
05:38:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
05:38:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
05:38:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
05:38:50 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 0x01918640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01952E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01892B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0196CE90>, '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 0x0136A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019D8520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-096', 'USERDOMAIN': 'T-W732-IX-096', '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-096', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
05:38:50 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test\build\tests\config
05:38:50 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 -r C:\slave\test\build\tests\config\mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub
05:38:50 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
05:38:50 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
05:38:50 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
05:38:50 INFO - 'COMPUTERNAME': 'T-W732-IX-096',
05:38:50 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
05:38:50 INFO - 'CYGWINBASE': 'C:\\cygwin',
05:38:50 INFO - 'DCLOCATION': 'SCL3',
05:38:50 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
05:38:50 INFO - 'FP_NO_HOST_CHECK': 'NO',
05:38:50 INFO - 'HOMEDRIVE': 'C:',
05:38:50 INFO - 'HOMEPATH': '\\Users\\cltbld',
05:38:50 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
05:38:50 INFO - 'KTS_VERSION': '1.19c',
05:38:50 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
05:38:50 INFO - 'LOGONSERVER': '\\\\T-W732-IX-096',
05:38:50 INFO - 'MONDIR': 'C:\\Monitor_config\\',
05:38:50 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
05:38:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
05:38:50 INFO - 'MOZ_NO_REMOTE': '1',
05:38:50 INFO - 'NO_EM_RESTART': '1',
05:38:50 INFO - 'NUMBER_OF_PROCESSORS': '8',
05:38:50 INFO - 'OS': 'Windows_NT',
05:38:50 INFO - 'OURDRIVE': 'C:',
05:38:50 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
05:38:50 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
05:38:50 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
05:38:50 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
05:38:50 INFO - 'PROCESSOR_LEVEL': '6',
05:38:50 INFO - 'PROCESSOR_REVISION': '1e05',
05:38:50 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
05:38:50 INFO - 'PROGRAMFILES': 'C:\\Program Files',
05:38:50 INFO - 'PROMPT': '$P$G',
05:38:50 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
05:38:50 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
05:38:50 INFO - 'PUBLIC': 'C:\\Users\\Public',
05:38:50 INFO - 'PWD': 'C:\\slave\\test',
05:38:50 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
05:38:50 INFO - 'SLAVEDIR': 'C:\\slave\\',
05:38:50 INFO - 'SYSTEMDRIVE': 'C:',
05:38:50 INFO - 'SYSTEMROOT': 'C:\\windows',
05:38:50 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
05:38:50 INFO - 'TEST1': 'testie',
05:38:50 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
05:38:50 INFO - 'USERDOMAIN': 'T-W732-IX-096',
05:38:50 INFO - 'USERNAME': 'cltbld',
05:38:50 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
05:38:50 INFO - 'WINDIR': 'C:\\windows',
05:38:50 INFO - 'WINDOWS_TRACING_FLAGS': '3',
05:38:50 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
05:38:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
05:38:50 INFO - Ignoring indexes: https://pypi.python.org/simple/
05:38:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
05:38:51 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mviirn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
05:38:51 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///C:/slave/test/build/tests/mozbase/manifestparser in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 1))
05:38:51 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
05:38:51 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-l27zj9-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
05:38:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///C:/slave/test/build/tests/mozbase/mozcrash in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2))
05:38:51 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
05:38:51 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xq7u4s-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
05:38:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///C:/slave/test/build/tests/mozbase/mozdebug in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 3))
05:38:51 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
05:38:51 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-skewzj-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
05:38:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///C:/slave/test/build/tests/mozbase/mozdevice in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4))
05:38:51 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
05:38:51 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ixvghi-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
05:38:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///C:/slave/test/build/tests/mozbase/mozfile in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 5))
05:38:51 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
05:38:51 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ntcxey-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
05:38:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///C:/slave/test/build/tests/mozbase/mozhttpd in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 6))
05:38:51 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
05:38:52 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vbtht7-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
05:38:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///C:/slave/test/build/tests/mozbase/mozinfo in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 7))
05:38:52 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
05:38:52 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-nssf7u-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
05:38:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///C:/slave/test/build/tests/mozbase/mozinstall in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 8))
05:38:52 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
05:38:52 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fflbre-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
05:38:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///C:/slave/test/build/tests/mozbase/mozleak in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 9))
05:38:52 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
05:38:52 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kibozj-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
05:38:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///C:/slave/test/build/tests/mozbase/mozlog in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10))
05:38:52 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
05:38:52 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-sfpxb1-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
05:38:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///C:/slave/test/build/tests/mozbase/moznetwork in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 11))
05:38:52 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
05:38:52 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ugrxm3-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
05:38:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///C:/slave/test/build/tests/mozbase/mozprocess in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 12))
05:38:52 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
05:38:52 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-sqfmmh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
05:38:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///C:/slave/test/build/tests/mozbase/mozprofile in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 13))
05:38:52 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
05:38:52 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wbdzi9-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
05:38:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///C:/slave/test/build/tests/mozbase/mozrunner in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 14))
05:38:53 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
05:38:53 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-_qze_j-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
05:38:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///C:/slave/test/build/tests/mozbase/mozscreenshot in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 15))
05:38:53 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
05:38:53 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-b9zdiy-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
05:38:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///C:/slave/test/build/tests/mozbase/moztest in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 16))
05:38:53 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
05:38:53 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dcd3et-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
05:38:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///C:/slave/test/build/tests/mozbase/mozversion in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 17))
05:38:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==0.17->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2))
05:38:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==0.17->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2))
05:38:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in c:\slave\test\build\venv\lib\site-packages (from mozdebug==0.1->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 3))
05:38:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.48->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4))
05:38:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.48->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4))
05:38:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10))
05:38:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
05:38:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
05:38:54 INFO - Downloading blessings-1.6.tar.gz
05:38:54 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings
05:38:54 INFO - Installing collected packages: blessings
05:38:54 INFO - Running setup.py install for blessings
05:38:55 INFO - Successfully installed blessings
05:38:55 INFO - Cleaning up...
05:38:57 INFO - Return code: 0
05:38:57 INFO - Done creating virtualenv C:\slave\test\build/venv.
05:38:57 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
05:38:57 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
05:38:58 INFO - Reading from file tmpfile_stdout
05:38:58 INFO - Using _rmtree_windows ...
05:38:58 INFO - Using _rmtree_windows ...
05:38:58 INFO - Current package versions:
05:38:58 INFO - blessings == 1.6
05:38:58 INFO - blobuploader == 1.2.4
05:38:58 INFO - docopt == 0.6.1
05:38:58 INFO - functools32 == 3.2.3-2
05:38:58 INFO - jsonschema == 2.5.1
05:38:58 INFO - manifestparser == 1.1
05:38:58 INFO - mozInstall == 1.12
05:38:58 INFO - mozcrash == 0.17
05:38:58 INFO - mozdebug == 0.1
05:38:58 INFO - mozdevice == 0.48
05:38:58 INFO - mozfile == 1.2
05:38:58 INFO - mozhttpd == 0.7
05:38:58 INFO - mozinfo == 0.9
05:38:58 INFO - mozleak == 0.1
05:38:58 INFO - mozlog == 3.4
05:38:58 INFO - moznetwork == 0.27
05:38:58 INFO - mozprocess == 0.23
05:38:58 INFO - mozprofile == 0.28
05:38:58 INFO - mozrunner == 6.12
05:38:58 INFO - mozscreenshot == 0.1
05:38:58 INFO - mozsystemmonitor == 0.3
05:38:58 INFO - moztest == 0.7
05:38:58 INFO - mozversion == 1.4
05:38:58 INFO - psutil == 3.1.1
05:38:58 INFO - requests == 1.2.3
05:38:58 INFO - Installing None into virtualenv C:\slave\test\build/venv
05:38:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
05:38:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
05:38:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
05:38:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
05:38:58 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 0x01918640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01952E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01892B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0196CE90>, '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 0x0136A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019D8520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-096', 'USERDOMAIN': 'T-W732-IX-096', '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-096', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
05:38:58 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\talos\\requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test\build\tests\talos
05:38:58 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 -r C:\slave\test\build\tests\talos\requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub
05:38:58 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
05:38:58 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
05:38:58 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
05:38:58 INFO - 'COMPUTERNAME': 'T-W732-IX-096',
05:38:58 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
05:38:58 INFO - 'CYGWINBASE': 'C:\\cygwin',
05:38:58 INFO - 'DCLOCATION': 'SCL3',
05:38:58 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
05:38:58 INFO - 'FP_NO_HOST_CHECK': 'NO',
05:38:58 INFO - 'HOMEDRIVE': 'C:',
05:38:58 INFO - 'HOMEPATH': '\\Users\\cltbld',
05:38:58 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
05:38:58 INFO - 'KTS_VERSION': '1.19c',
05:38:58 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
05:38:58 INFO - 'LOGONSERVER': '\\\\T-W732-IX-096',
05:38:58 INFO - 'MONDIR': 'C:\\Monitor_config\\',
05:38:58 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
05:38:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
05:38:58 INFO - 'MOZ_NO_REMOTE': '1',
05:38:58 INFO - 'NO_EM_RESTART': '1',
05:38:58 INFO - 'NUMBER_OF_PROCESSORS': '8',
05:38:58 INFO - 'OS': 'Windows_NT',
05:38:58 INFO - 'OURDRIVE': 'C:',
05:38:58 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
05:38:58 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
05:38:58 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
05:38:58 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
05:38:58 INFO - 'PROCESSOR_LEVEL': '6',
05:38:58 INFO - 'PROCESSOR_REVISION': '1e05',
05:38:58 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
05:38:58 INFO - 'PROGRAMFILES': 'C:\\Program Files',
05:38:58 INFO - 'PROMPT': '$P$G',
05:38:58 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
05:38:58 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
05:38:58 INFO - 'PUBLIC': 'C:\\Users\\Public',
05:38:58 INFO - 'PWD': 'C:\\slave\\test',
05:38:58 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
05:38:58 INFO - 'SLAVEDIR': 'C:\\slave\\',
05:38:58 INFO - 'SYSTEMDRIVE': 'C:',
05:38:58 INFO - 'SYSTEMROOT': 'C:\\windows',
05:38:58 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
05:38:58 INFO - 'TEST1': 'testie',
05:38:58 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
05:38:58 INFO - 'USERDOMAIN': 'T-W732-IX-096',
05:38:58 INFO - 'USERNAME': 'cltbld',
05:38:58 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
05:38:58 INFO - 'WINDIR': 'C:\\windows',
05:38:58 INFO - 'WINDOWS_TRACING_FLAGS': '3',
05:38:58 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
05:38:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
05:38:58 INFO - Ignoring indexes: https://pypi.python.org/simple/
05:38:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 1))
05:38:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 2))
05:38:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 3))
05:38:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 4))
05:38:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 5))
05:38:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 6))
05:38:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 7))
05:38:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 8))
05:38:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 9))
05:38:58 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in c:\slave\test\build\venv\lib\site-packages (from mozlog>=3.1->-r C:\slave\test\build\tests\talos\requirements.txt (line 1))
05:38:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test\build\venv\lib\site-packages (from mozhttpd>=0.7->-r C:\slave\test\build\tests\talos\requirements.txt (line 4))
05:38:58 INFO - Cleaning up...
05:38:58 INFO - Return code: 0
05:38:58 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv
05:38:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
05:38:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
05:38:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
05:38:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
05:38:58 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 0x01918640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01952E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01892B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0196CE90>, '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 0x0136A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019D8520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-096', 'USERDOMAIN': 'T-W732-IX-096', '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-096', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
05:38:58 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in C:\slave\test\build
05:38:58 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema
05:38:58 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
05:38:58 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
05:38:58 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
05:38:58 INFO - 'COMPUTERNAME': 'T-W732-IX-096',
05:38:58 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
05:38:58 INFO - 'CYGWINBASE': 'C:\\cygwin',
05:38:58 INFO - 'DCLOCATION': 'SCL3',
05:38:58 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
05:38:58 INFO - 'FP_NO_HOST_CHECK': 'NO',
05:38:58 INFO - 'HOMEDRIVE': 'C:',
05:38:58 INFO - 'HOMEPATH': '\\Users\\cltbld',
05:38:58 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
05:38:58 INFO - 'KTS_VERSION': '1.19c',
05:38:58 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
05:38:58 INFO - 'LOGONSERVER': '\\\\T-W732-IX-096',
05:38:58 INFO - 'MONDIR': 'C:\\Monitor_config\\',
05:38:58 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
05:38:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
05:38:58 INFO - 'MOZ_NO_REMOTE': '1',
05:38:58 INFO - 'NO_EM_RESTART': '1',
05:38:58 INFO - 'NUMBER_OF_PROCESSORS': '8',
05:38:58 INFO - 'OS': 'Windows_NT',
05:38:58 INFO - 'OURDRIVE': 'C:',
05:38:58 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
05:38:58 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
05:38:58 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
05:38:58 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
05:38:58 INFO - 'PROCESSOR_LEVEL': '6',
05:38:58 INFO - 'PROCESSOR_REVISION': '1e05',
05:38:58 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
05:38:58 INFO - 'PROGRAMFILES': 'C:\\Program Files',
05:38:58 INFO - 'PROMPT': '$P$G',
05:38:58 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
05:38:58 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
05:38:58 INFO - 'PUBLIC': 'C:\\Users\\Public',
05:38:58 INFO - 'PWD': 'C:\\slave\\test',
05:38:58 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
05:38:58 INFO - 'SLAVEDIR': 'C:\\slave\\',
05:38:58 INFO - 'SYSTEMDRIVE': 'C:',
05:38:58 INFO - 'SYSTEMROOT': 'C:\\windows',
05:38:58 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
05:38:58 INFO - 'TEST1': 'testie',
05:38:58 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
05:38:58 INFO - 'USERDOMAIN': 'T-W732-IX-096',
05:38:58 INFO - 'USERNAME': 'cltbld',
05:38:58 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
05:38:58 INFO - 'WINDIR': 'C:\\windows',
05:38:58 INFO - 'WINDOWS_TRACING_FLAGS': '3',
05:38:58 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
05:38:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
05:38:58 INFO - Ignoring indexes: https://pypi.python.org/simple/
05:38:58 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages
05:38:58 INFO - Cleaning up...
05:38:58 INFO - Return code: 0
05:38:58 INFO - Running post-action listener: _resource_record_post_action
05:38:58 INFO - Running post-action listener: _start_resource_monitoring
05:38:58 INFO - Starting resource monitoring.
05:38:59 INFO - [mozharness: 2016-10-15 12:38:59.017000Z] Finished create-virtualenv step (success)
05:38:59 INFO - [mozharness: 2016-10-15 12:38:59.017000Z] Running install step.
05:38:59 INFO - Running pre-action listener: _resource_record_pre_action
05:38:59 INFO - Running main action method: install
05:38:59 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
05:38:59 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
05:38:59 INFO - Reading from file tmpfile_stdout
05:38:59 INFO - Using _rmtree_windows ...
05:38:59 INFO - Using _rmtree_windows ...
05:38:59 INFO - Detecting whether we're running mozinstall >=1.0...
05:38:59 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h']
05:38:59 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h
05:38:59 INFO - Reading from file tmpfile_stdout
05:38:59 INFO - Output received:
05:38:59 INFO - Usage: mozinstall-script.py [options] installer
05:38:59 INFO - Options:
05:38:59 INFO - -h, --help show this help message and exit
05:38:59 INFO - -d DEST, --destination=DEST
05:38:59 INFO - Directory to install application into. [default:
05:38:59 INFO - "C:\slave\test"]
05:38:59 INFO - --app=APP Application being installed. [default: firefox]
05:38:59 INFO - Using _rmtree_windows ...
05:38:59 INFO - Using _rmtree_windows ...
05:38:59 INFO - mkdir: C:\slave\test\build\application
05:38:59 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.zip', '--destination', 'C:\\slave\\test\\build\\application']
05:38:59 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
05:39:01 INFO - Reading from file tmpfile_stdout
05:39:01 INFO - Output received:
05:39:01 INFO - C:\slave\test\build\application\firefox\firefox.exe
05:39:01 INFO - Using _rmtree_windows ...
05:39:01 INFO - Using _rmtree_windows ...
05:39:01 INFO - Running post-action listener: _resource_record_post_action
05:39:01 INFO - [mozharness: 2016-10-15 12:39:01.396000Z] Finished install step (success)
05:39:01 INFO - [mozharness: 2016-10-15 12:39:01.396000Z] Running run-tests step.
05:39:01 INFO - Running pre-action listener: _resource_record_pre_action
05:39:01 INFO - Running main action method: run_tests
05:39:01 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version']
05:39:01 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version
05:39:01 INFO - Python 2.7.3
05:39:01 INFO - Return code: 0
05:39:01 INFO - grabbing minidump binary from tooltool
05:39:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
05:39:01 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 0x0196CE90>, '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 0x0136A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019D8520>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1
05:39:01 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
05:39:01 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
05:39:01 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
05:39:01 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'...
05:39:02 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmprffoo6
05:39:02 INFO - INFO - File integrity verified, renaming tmprffoo6 to win32-minidump_stackwalk.exe
05:39:02 INFO - Return code: 0
05:39:02 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755
05:39:02 INFO - mkdir: C:\slave\test\build\blobber_upload_dir
05:39:02 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir
05:39:02 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe
05:39:02 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir
05:39:02 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476527404/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-096', '--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
05:39:02 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476527404/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-096 --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
05:39:02 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
05:39:02 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
05:39:02 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
05:39:02 INFO - 'COMPUTERNAME': 'T-W732-IX-096',
05:39:02 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
05:39:02 INFO - 'CYGWINBASE': 'C:\\cygwin',
05:39:02 INFO - 'DCLOCATION': 'SCL3',
05:39:02 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
05:39:02 INFO - 'FP_NO_HOST_CHECK': 'NO',
05:39:02 INFO - 'HOMEDRIVE': 'C:',
05:39:02 INFO - 'HOMEPATH': '\\Users\\cltbld',
05:39:02 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
05:39:02 INFO - 'KTS_VERSION': '1.19c',
05:39:02 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
05:39:02 INFO - 'LOGONSERVER': '\\\\T-W732-IX-096',
05:39:02 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir',
05:39:02 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe',
05:39:02 INFO - 'MONDIR': 'C:\\Monitor_config\\',
05:39:02 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
05:39:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
05:39:02 INFO - 'MOZ_NO_REMOTE': '1',
05:39:02 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir',
05:39:02 INFO - 'NO_EM_RESTART': '1',
05:39:02 INFO - 'NUMBER_OF_PROCESSORS': '8',
05:39:02 INFO - 'OS': 'Windows_NT',
05:39:02 INFO - 'OURDRIVE': 'C:',
05:39:02 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
05:39:02 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
05:39:02 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
05:39:02 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
05:39:02 INFO - 'PROCESSOR_LEVEL': '6',
05:39:02 INFO - 'PROCESSOR_REVISION': '1e05',
05:39:02 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
05:39:02 INFO - 'PROGRAMFILES': 'C:\\Program Files',
05:39:02 INFO - 'PROMPT': '$P$G',
05:39:02 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
05:39:02 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
05:39:02 INFO - 'PUBLIC': 'C:\\Users\\Public',
05:39:02 INFO - 'PWD': 'C:\\slave\\test',
05:39:02 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos',
05:39:02 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
05:39:02 INFO - 'SLAVEDIR': 'C:\\slave\\',
05:39:02 INFO - 'SYSTEMDRIVE': 'C:',
05:39:02 INFO - 'SYSTEMROOT': 'C:\\windows',
05:39:02 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
05:39:02 INFO - 'TEST1': 'testie',
05:39:02 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
05:39:02 INFO - 'USERDOMAIN': 'T-W732-IX-096',
05:39:02 INFO - 'USERNAME': 'cltbld',
05:39:02 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
05:39:02 INFO - 'WINDIR': 'C:\\windows',
05:39:02 INFO - 'WINDOWS_TRACING_FLAGS': '3',
05:39:02 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
05:39:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
05:39:02 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476527404/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-096', '--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
05:39:03 INFO - mozversion application_buildid: 20161015033004
05:39:03 INFO - mozversion application_changeset: 44b01519b5668699f2c1a10d362445be59711d35
05:39:03 INFO - mozversion application_display_name: Nightly
05:39:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
05:39:03 INFO - mozversion application_name: Firefox
05:39:03 INFO - mozversion application_remotingname: firefox
05:39:03 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team
05:39:03 INFO - mozversion application_vendor: Mozilla
05:39:03 INFO - mozversion application_version: 52.0a1
05:39:03 INFO - mozversion platform_buildid: 20161015033004
05:39:03 INFO - mozversion platform_changeset: 44b01519b5668699f2c1a10d362445be59711d35
05:39:03 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team
05:39:03 INFO - mozversion platform_version: 52.0a1
05:39:03 INFO - using testdate: 1476535143
05:39:03 INFO - actual date: 1476535143
05:39:03 INFO - starting webserver on 'localhost:49889'
05:39:03 INFO - SUITE-START | Running 2 tests
05:39:03 INFO - TEST-START | dromaeo_css
05:39:03 INFO - operating with platform_type : w7_
05:39:03 INFO - Initialising browser for dromaeo_css test...
05:39:03 INFO - TEST-INFO | started process 4020 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmp9xdb7b\profile http://localhost:49889/getInfo.html)
05:39:10 INFO - PROCESS | 4020 | __metrics Screen width/height:1600/1200
05:39:10 INFO - PROCESS | 4020 | colorDepth:24
05:39:10 INFO - PROCESS | 4020 | Browser inner width/height: 1010/674
05:39:10 INFO - PROCESS | 4020 | __metrics
05:39:11 INFO - PROCESS | 4020 | 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"
05:39:11 INFO - PROCESS | 4020 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
05:39:12 INFO - TEST-INFO | 4020: exit 0
05:39:12 INFO - Browser initialized.
05:39:12 INFO - Running cycle 1/1 for dromaeo_css test...
05:39:12 INFO - TEST-INFO | started process 3712 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmp9xdb7b\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1)
05:39:13 INFO - PROCESS | 3712 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0
05:39:24 INFO - PROCESS | 3712 | RSS: Main: 122724352
05:39:24 INFO - PROCESS | 3712 |
05:40:15 INFO - PROCESS | 3712 | 1476535215033 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]
05:42:26 INFO - PROCESS | 3712 | Cycle 1(1): loaded http://localhost:49889/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49889/tests/dromaeo/cssquery-ext.html)
05:42:28 INFO - PROCESS | 3712 | RSS: Main: 197029888
05:42:28 INFO - PROCESS | 3712 |
05:45:30 INFO - PROCESS | 3712 | Cycle 1(1): loaded http://localhost:49889/tests/dromaeo/cssquery-ext.html (next: http://localhost:49889/tests/dromaeo/cssquery-jquery.html)
05:45:31 INFO - PROCESS | 3712 | RSS: Main: 199815168
05:45:31 INFO - PROCESS | 3712 |
05:48:33 INFO - PROCESS | 3712 | Cycle 1(1): loaded http://localhost:49889/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49889/tests/dromaeo/cssquery-mootools.html)
05:48:33 INFO - PROCESS | 3712 | RSS: Main: 145596416
05:48:33 INFO - PROCESS | 3712 |
05:51:35 INFO - PROCESS | 3712 | Cycle 1(1): loaded http://localhost:49889/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49889/tests/dromaeo/cssquery-prototype.html)
05:51:36 INFO - PROCESS | 3712 | RSS: Main: 136265728
05:51:36 INFO - PROCESS | 3712 |
05:54:39 INFO - PROCESS | 3712 | Cycle 1(1): loaded http://localhost:49889/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49889/tests/dromaeo/cssquery-yui.html)
05:54:39 INFO - PROCESS | 3712 | RSS: Main: 132345856
05:54:39 INFO - PROCESS | 3712 |
05:57:42 INFO - PROCESS | 3712 | Cycle 1(1): loaded http://localhost:49889/tests/dromaeo/cssquery-yui.html (next: http://localhost:49889/tests/dromaeo/cssquery-dojo.html)
05:57:42 INFO - PROCESS | 3712 | RSS: Main: 181784576
05:57:42 INFO - PROCESS | 3712 |
05:57:42 INFO - PROCESS | 3712 | __start_tp_report
05:57:42 INFO - PROCESS | 3712 | _x_x_mozilla_page_load
05:57:42 INFO - PROCESS | 3712 | _x_x_mozilla_page_load_details
05:57:42 INFO - PROCESS | 3712 | |i|pagename|runs|
05:57:42 INFO - PROCESS | 3712 | |0;dojo.html;2664.3871909460822;2675.8528280944543;2681.8659067046656;2683.3023413912383;2707.702152763197;4539.054412724264;4563.680542362036;4644.071185762844;4653.348531205634;4658.7204767713965;4539.750313732743;4635.884102897429;4650.883727906804;4670.159371313162;4792.04159168166;4157.106222162234;4179.3939878717565;4198.097409057053;4202.474690663667;4209;4059.7158198926086;4091.509018917734;4179.770112643803;4185.476815398075;4192.329227323628;4082.101937573729;4094.4472496213075;4142.937855932163;4157.937630935538;4193.056562273495;4106.527749308838;4154.459920210384;4173.728707633994;4197.622214000755;4242.639375653073;3995.860144894944;4069.9621596493002;4094.1811637672286;4104.56902025289;4209.137126888981;4580.931286030712;4618.053299073682;4657.347971284023;4666.160091183586;4691.88270293242;4698.859034228944;4728.834490792806;4820.421549414058;4929.65492415531;4974.3533340665845;4747.928781068286;4770.546798054179;4791.736454495001;4898.657094003421;4903.068417000758;4504.594586487232;4505.414296141514;4510.729356238669;4511.887202819923;4537.863864084083;4926.310316555685;4932.827351042696;4933.0627180836245;4936.7284799336385;4944.802207911644;4728.905421891543;4734.171519983989;4754.572088512051;4770.833020844254;4807.6394270429855;4785.760711964388;4809.470958194595;4809.879753006133;4935.087008903364;4963;4735.692180008288;4761.381020467386;4772.904541909212;4779.378680771478;4783.162946484351;4770.856874293777;4775.379200703956;4881.658283920092;4901.534354236342;4911.042346742351;3264.6735326467638;3298.752593555493;3326;3341.3651406233225;3348.7655864089766;2863.914082577526;2908.520094184436;2980.2996295870453;3028.9242768930512;3084.1056093733014;4469.843555475542;4521.570450807168;4524.34397012438;4576.496585375606;4616.95383046172;4733.384659994179;4785.59322457588;4924.43369012561;4936.654434189644;4951.405831300195;4427.977860110679;4587.197240482902;4606.30905364198;4648.511906249795;4757.215059515142;4093.713440059167;4112.239235741397;4116.156187981456;4125.834966601363;4165.541790403053;4966.652334336562;5090.4655011223285;5176.9741151294;5186.299849520342;5208.062548741263;4816.95183048179;4837.129316722883;4852;4949.653524253267;4956.677815941952;4977.178765503651;5056.721880296472;5069.822556210515;5117.232415137759;5207.661502002357;4860.6111511078325;4880.633952453509;4890.462049174659;4922.507749225048;4932.4081110267;4841.733704646137;4881.968166649409;4961.156603377362;4962.082014827269;4982.10322142018;4696.31903374002;4815.15734746425;4834.274858771212;4919.3604831371695;4941.160002799461;4688.0858232644305;4771.212749896367;4852.344933433941;4879.975205206804;4901.2893130496595;4620.306953956933;4670;4704.435467743892;4822.95177048225;4829.782659780389;4850.054239423279;4852.102361063215;4890.364252647133;4940.6788558743565;4951;4769.856904292877;4770.642201834807;4770.809167633256;4799.136155492045;4821.951780482291
05:57:42 INFO - PROCESS | 3712 | |1;ext.html;520.3911423634348;534.5456362092219;537.9858965849376;539.16967869481;543.7960764713231;29535.40929181415;29993;40515.18969620606;43085;43513.12973740523;40791.59208407915;41697;41748.791256043754;41978.58021419785;41994;8147.714829980937;8273.95863020684;8316.501009939393;8337.832703421525;8555.743327700178;6817.5227734058635;6966.790996270095;9771.95114024429;9959.352642078278;9986.300958932878;9975.700728978143;10038.297319187659;10103.545340459697;10366.481675916211;10384.63653772118;10047.547860346302;10065.949670251675;10070.496475176249;10268.486575671224;10269.640562580274;6882.8623427531165;7075.292470752935;9802.852957205647;10156.441395723232;10161.644342448053;37726.24547509034;37969;38060.80969595162;38194.23611527754;38337.61662383368;2933.911982640524;3016.9849150754326;3033.9089827305215;3212.726918211955;3238.433274177033;29132.563011554845;29319.560206596918;29857.969174161644;29980.55029174564;30888.53667194994;3185.108169712461;3343.314620502791;3389.57630296213;3397.252604427022;3491.7032052275586;330724.46959547274;331790;334106;335932;338069;51177.95288188431;51553.22670159951;54219.45780542144;54492.72753636286;54660.72669636626;27163.456730865273;55332.17001744977;56636.43363566394;56825.43174568284;56940.71529642325;13807.861921380858;14054.929725351309;14505.782413263809;14735.189564574051;14758.926205368904;9681.467519286365;10897.618583349546;11195.104391648649;11241.21311508203;11241.887581124247;15054.698906021818;17282.740758888627;17590.560235994202;17666.293348265925;18014;6595.274519802818;6669.866602667919;6685.298043705438;6704.932950670431;6728.461723062143;784.8391079828622;825.108882406999;869.2698133567833;905.2215095018279;910.6084383715066;27816.72183278141;28140.71859281422;28881.56677649837;28902.566461503095;28977.7102228975;918.5912269040347;935.1396715022196;941.7928055827707;999.6101520407047;1065.577014405571;14501.854981450262;15105.093694378373;15276.465323713617;15345.156016418981;15465.072095674295;492439.5378023087;493455;500336;506568;506927;51134.48865511372;51413.22880156801;51511.742441288305;51523;51585.7420712894;1021.8620486234263;1036.5594622285648;1066.072516910293;1156.6587856582328;1169.4562028656546;24254;29769.851150744973;29946.70053299439;30444;30768.538471923388;47917.76041119772;48128;48556.5144348552;48882.266765997825;49314;433546;434082;443934;450970.09963455715;451032;22508.437289067904;22873;22935.082596695946;23142.190023349103;23937.401564961016;42613.5738642622;42825.14349712926;43506;43851.56148438475;44328.778356108014;36087.22604383286;36986.44520332251;37676.81161594175;37898.43152352662;38530;36506.087347816516;37342.62657373391;37506.812465937495;37728.62271377251;37750.2449950994;34653.306933861684;35303;36224.637753622126;36293.81853090718;36383.63616363909;32833;32964.67035329712;33206.33587328192;33638.495422568194;34160.82919585386;25172.748272517772;25534.489310214063;25715.871420643525;25756.227313180636;26380.472390552466
05:57:42 INFO - PROCESS | 3712 | |2;jquery.html;285.6030118135793;297.399253507914;307.8830044583059;308.7314036787993;315.2465607198795;12989.48042078317;17231.396901108463;17327.22027508762;17685.469435916926;18407.355742549014;16596.50210493687;16866.409675661354;16914.1542922854;17160.39938602149;17372;5261.76322065507;5460.099083651193;5465.316835395575;5469.15228139637;5474.863128421782;5277.788888444457;5386.596005299599;5399.7030163341;5459.153831156177;5555.166724991243;5342.652727572715;5348.679079255237;5356.169793681966;5377.193420986864;5505.559555235592;5254.448282930294;5360.678359298455;5369.919451208235;5384.730763461811;5462.8361149165585;4516.096780643851;4596.448426188846;4618.399608050931;4621.792019359137;4623.190941585242;21537.67693484599;21932;21948.231811886668;22001.22995695159;22339.77660223393;1770.5396596884773;1832.352663228105;2684.9597256041175;2704.3644743485265;2736.7263273672675;13259.66850828737;13992.090514116651;14213.360398781977;14271.643208919757;14779.187144707037;1749.7550342951997;1782.8306310900573;1791.70436877915;1804.584945462538;1838.4760343302228;79233;81611.59194203992;82148;82453.58773206155;82498.17501824965;8243.505389676639;8254.399864042807;8342.958285208535;8396.160383961676;8751.693690720795;10137.290389672653;10685.931406859252;10970.177236707279;11287.604933827277;11402.771944561062;6932.757353492603;6991.7552885649775;7128.429725622042;7252.419806415474;7260.818479538054;7392.778216653509;7431.145418276859;7458.962705186439;7564.167941526428;7594.848103037908;5494.945050549523;5547.317403290187;5647.407022262685;5661;5747.195392645033;1426.322496814005;1436.8419473857866;1452.3682198243587;1456.868881800643;1507.2765072764914;302.8637113299024;312.5187211693966;318.32673894712514;334.448160535119;885.3006125161179;9288.907110928803;9508.383574792302;9516.333856630108;9658.951705241569;10043.899561004442;335.60063524405876;335.99496007559907;336.8719886443136;341.23903694760327;351.07841914973193;5045.697258164522;5069.138246498104;5101.438841727407;5135.38375394955;5216.530512253916;446872.01587864745;450934;451418;459370;474261;97266;98488.52267215998;101010;101594.4920275394;102156;349.5892326516333;353.19823999520946;354.2808099557902;355.5306994766883;880.7577916073132;16248.593785155466;16259.756103658217;16702.66594668124;16870.409535666193;16905;30267.69732302649;40165.79917100396;41720.001190723924;44389.556104439835;45181.77409112934;58968;60206;60476.697616511636;60535.39464605298;61530.38469615247;5719;6089.5432842537875;6131.448169664752;6219.937800621936;6895.586264824126;12113.878861211275;12248.632541023784;12411.565595204125;12567.308798016196;12638.052146089263;10227.897721022695;10283.07452329294;10350.911974623434;10400.531976060944;10614.097801686943;9721.9027809721;10074.69775906724;10267.794644107225;10297.53661085268;10478.944713327513;10413.895861041292;10756.193285503776;10816.67549973502;10858.239923205616;10984.670459886216;5876.471117599265;6034.487068599218;6188.81433556994;6206.906896396467;6290.842728931813;9504.95247523758;10366.740831479274;10524.31591946521;10535.157187424835;10781.814000459786
05:57:42 INFO - PROCESS | 3712 | |3;mootools.html;1182.5801840346676;1190.5416414680346;1217.945192466339;1519.217602934489;1549.3957356630922;6745.730170793168;6870.312968703133;7198.244184360646;7203.81990450239;7397.63011849408;6545.770898018571;6717.16035495563;7086.787396378104;7281.635918204095;7325.0477437933;1914.7415098961635;1926.1043614719154;1943.9125239364262;1944.6013567218756;1946.338244996701;1856.071964017991;1861.1810803246597;1908.3988543608789;1913.4929243750416;1933.584279379933;1678.9664206715859;1733.6099377640055;1860.6092720528704;1877.9436616901514;1914.550080731027;1832.496063582519;1854.6847036003776;1859.3213477080903;1859.7861245956685;1862.9347972820885;1739.1217435195272;1767.5139336682387;1778.8399044086095;1809.6109336492586;1840.7146892231592;6995.82510437238;7061.258567850405;7103.147622285359;7232.385247253989;7237.240089790602;4145.233131870614;4251.149770045972;4261.552536983634;4309.784510774449;4373.737575745466;2848.159792861111;2913.388188480422;2917.591537184796;2921.255079954598;2978.627671541057;898.1557336104041;929.363386080537;932.8600709893502;934.1545900959643;934.8831396075491;9078.636854525812;9179.541022948893;9394.906050939471;9401.341906066578;9407.670731524364;3623.3296840084668;3748.11919198988;3750.1187221003042;3792.2984247914223;3792.3173828711097;7177.964110179416;7214.458915581247;7239.638018099179;7380.889286660705;7464.738734144279;4290.613844753987;4291.527931927485;4292.184484947912;4295.634871035997;4300.290452075373;4063;4066.2070896175555;4073.185362927367;4076.9184616308103;4078.7144899856726;2769.709180536055;2847.3024109093403;2863.4559433707527;2894.3343031102545;2904.622399088147;815.5727476915398;822.6298165825283;825.2407784837964;827.4414770030326;831.6673330667782;746.1866565443693;753.7701001194579;794.3208556684067;799.0531220503722;803;4555.612772914273;4790.952090479121;4807.927881081787;4974.7512624369365;5002.774875130628;784.8901153838467;796.4066770256196;813.4671789977574;831.7172161465131;831.9126491718404;3039.4680930837017;3057.7248047675816;3129.4523458395147;3223.8226897520863;3291.8518666660057;8473.025602055719;8522.786930326667;8565.57172141403;8601;8633.740987770378;8460.703875364332;8514.57427128641;8680.218780309802;8687.04442511336;8698.47809131454;6861.862762744717;6892.827679308058;6942.305769423017;6942.652867356612;6948.930510694829;4619.745913974763;4722.716637001791;4745.217039188496;4842.249451335048;4843.1524483216;7949.483283586548;7975.5613441261285;8184.386171037077;8194.098649148707;8206.958965205136;8014.318782903519;8018.83962320762;8099.757007289791;8139.7965050871835;8203.42576019673;6030.6080104793045;6122;6202.193714817045;6222.844428889314;6287.2769631492965;6525;6632.602043877383;6738.561993470409;6759.357861003394;6762.695678694372;5118.948810511847;5757.568182386254;5797.594168408171;6266.154069200599;6267.373262673696;6129.632222066691;6207.441330280297;6210.441060304594;6221.377862213742;6292.622442653455;5431.646942948696;5447.673139611636;5529.695866727207;5535.833924982257;5621.381648018633;3957.881263562098;4029.3351596986167;4085.285075111784;4107.54816970139;4148.336266197394;4016.658584020391;4028.0332720146366;4126.339785634284;4151.501819781645;4151.546958564479
05:57:42 INFO - PROCESS | 3712 | |4;prototype.html;236.27581462816477;241.94798118404546;242.11506942126533;243.5409253557045;244.44632906466845;4829.975850120753;4924.655274130812;4954.1082605131105;4964.900701985963;5022.849314520561;4752.405949256343;4774.832880849171;4908.435529914061;4966.701997880121;4992.201247800353;3604.531410916577;3608.5669719633615;3609.2240168363796;3611.620779818121;3615.7220950800956;3515.437529995201;3617.47546605742;3619.1856832212675;3621.2214373909605;3645.2891686121084;3470.548828652272;3484.529588505545;3506.50908872758;3561.1809283864595;3627.963720362789;3535.575730912294;3536.169000284931;3537.6108628050893;3594.748367614268;3623.7644553103964;3116.065180445857;3124.7343975762087;3126.249700071989;3133.388989147117;3136.702013308732;4829.372181616367;4908.772806798301;4918.336024636664;4924.655274130812;4928.605711543068;4002.979985100085;4103.363978583325;4136.565660605623;4211.115665710205;4221.535631080578;5182.378114626231;5188.455212202702;5195.272661827347;5195.584353251731;5195.9480405195845;3361.428557145291;3398.2863598644312;3409.573803274591;3416.8633254669785;3459.117924929152;5279.366476022863;5290.0477913975465;5298.470152984709;5300.284461597674;5304.655197412156;3365.956553468384;3437.724982001434;3441.6214216436174;3444.0873168610337;3528.8650366528395;3437.415639341318;3513.2797776455764;3515.5781306243416;3530.452779819132;3535.045537704805;4174.5616710245595;4176.749395036247;4178.206140833232;4181.289180839264;4187.476565429321;5238.633295669267;5242.711650859239;5252.422233554306;5256.947430525723;5268.78924843002;1205.4274219745726;1229.465182645552;1246.7194881151668;1259.2759163481035;1261.413442749126;757.9805162057024;767.0795045944889;771.9807004824925;775.1937984496174;813.6501304439148;4789.856304310876;4800.543948324904;4831.028917215957;4834.20235661112;4877.463479017305;5151.5621172200035;5151.97424012885;5155.97422012895;5158.226266060046;5158.948410515922;4786.8085276588545;4789.161896668069;4848.151573474628;4941.802327906844;4942.5304596063315;2680.664916885389;2811.9718802812117;2827.844468554208;2831.957520637192;2835.149455163443;5496.972515137479;5498.1752737089755;5504.834854954358;5519.31008623922;5523.033469142884;5412.161115027177;5422.132458806572;5423.2136340230445;5430.104032834538;5466.972665136728;5211.009908117446;5268.762905669178;5364.383095943938;5368.57051435892;5373;5202.505761952609;5248.186531087687;5331.280277162534;5340.893182136413;5341;5268.341457317682;5274.947250527445;5278.102722537101;5281.339832520935;5283.735813209401;5168.870778230575;5355.22349259363;5492.59938715625;5493.23094766725;5499.50504454601;3506.1585219547637;3513.771604845677;3515.5254040704176;3568.9643103568633;3610.24184921159;5250.632455728062;5294.258803767399;5311.707856067954;5314.840554783363;5317.415084340642;5060.746962651926;5183.377994640668;5188.922166167435;5191.740412979411;5194.35070616173;4981.3026176334615;5098.566621837185;5103.234514822806;5214.765335559985;5215.374155101412;4945.727984960861;4962.181240095489;5049.823256186016;5057.089723849743;5059.797608095635;4911.803527858989;5060.316857224375;5133.4096578893905;5137.17805151174;5193.961207758539;5205.115130427912;5207.3751149862255;5210.244514545294;5210.505002024802;5214.374275087014
05:57:42 INFO - PROCESS | 3712 | |5;yui.html;630.0737915264565;631.3055638797321;701.4809041309433;707.8336590901138;708.2917082917085;13640.18158910466;14358.71282574348;14982.175980321077;15395.615109622237;15599.92200039001;14318.92840535796;14456.204908730017;14488.348024338904;14498.782518262207;14521.346539405726;635.0632816595507;635.3963734452266;636.0935666674969;636.7198432689602;642.3223499208333;515.945825688303;527.6069328350384;628.1018144054001;638.9840253993664;643.6009674002125;675.1459403854104;677.3835809413434;678.2098854834112;683.7470136049644;696.4184905603827;692.5982929900671;694.1392673085388;694.4583225084417;695.5826504097552;695.6278391060758;402.937544680575;407.521162633904;409.586317819002;490.8404768450268;497.9377577802775;13952.162870227718;14203.857961420461;14302.92848535761;14323.641908952359;14328.785068223984;12151.939240303831;12257.325847078497;12357.75284494305;12513.562025329069;12663.30351830649;12858.485660573566;12869.356532173448;12984.415701293465;13087.280199589019;13134.540291089766;4248.214080395137;4303.827846886152;4317.913641727179;4354.88950317667;4387.517373088957;79807;81161.59419202866;81617;82086.58956705238;82880.58559707283;10123.696289111338;10152.695419137437;10170.898291017143;10285.02292282232;10410.68767936963;18073.367432140076;18182.27270909149;18265;18380;18409.723854142198;6795.388415042571;6797.932020679828;6838.316168383221;6922.896156557656;7040.894386584206;3442.9655703442645;3447.793132412063;3491.389006923829;3555.0756803231347;3580.051286409104;2777.9583306250424;2780.4856101621267;2785.0670025541267;2864.0691775172736;2872.468593310244;474.4188369247644;483.88144904498625;486.5207770346206;491.4004914004908;496.3745680442668;518.7017464957589;635.5519358851998;650.5868773328875;653.1378580274088;673.2964052565081;9131.497767622712;9193.172614464731;9281.257499400032;9369.434092167763;9541.761455963657;638.6200210874522;639.859230969186;651.1957732200729;656.3961155736733;679.864027194563;4339.674524410681;4456.242438785414;4503.234450143484;4669.836555720602;4683.484816670163;89959.55020224988;120409.39795300967;121983;122839.3858030722;124445;102179;103984;106615;107683.46158269158;109130.45434772775;94199.52900235455;94252.0574794257;94876.52561737147;95866;96706;8440.620172092393;8552.529610871214;8642.35182361338;8872.955635221782;8955.149260820212;54606;54979.725101374235;55099.72450137723;55693.72153139208;56668;59298.40701592929;64778.67610661917;64888.35111648823;66610.3338966604;75952;12117;12263.386830658254;12268.754624907631;12592.370381481072;12919.289439080942;14018.158910465405;14332.57002289933;14845.92577037151;14871.851281487046;15012.149853158251;13013.154144980546;13080.215187088805;13207.207567545978;13296.867031329562;13731.107478013897;10884;12807.423665934868;13317;13449.394777235244;13692.794608080687;12207.511699531919;12774.552890649155;13218.024760160868;13252;13611.319434028457;10965.232433729563;11327.32036077838;11372.943135284271;11393.14551408631;11594.536218551164;10365.585376584851;10845.295055821347;10888.945555272174;11089.722756931142;11200.159988001096
05:57:42 INFO - PROCESS | 3712 | __end_tp_report
05:57:42 INFO - PROCESS | 3712 | __start_cc_report
05:57:42 INFO - PROCESS | 3712 | _x_x_mozilla_cycle_collect,1058
05:57:42 INFO - PROCESS | 3712 | __end_cc_report
05:57:42 INFO - PROCESS | 3712 | __startTimestamp1476536262651__endTimestamp
05:57:42 INFO - PROCESS | 3712 |
05:57:42 INFO - PROCESS | 3712 | ------- Summary: start -------
05:57:42 INFO - PROCESS | 3712 | Number of tests: 6
05:57:42 INFO - PROCESS | 3712 |
05:57:42 INFO - PROCESS | 3712 | [#0] dojo.html Cycles:165 Average:4517.60 Median:4734.17 stddev:569.72 (12.0%) stddev-sans-first:552.61
05:57:42 INFO - PROCESS | 3712 | Values: 2664.4 2675.9 2681.9 2683.3 2707.7 4539.1 4563.7 4644.1 4653.3 4658.7 4539.8 4635.9 4650.9 4670.2 4792.0 4157.1 4179.4 4198.1 4202.5 4209.0 4059.7 4091.5 4179.8 4185.5 4192.3 4082.1 4094.4 4142.9 4157.9 4193.1 4106.5 4154.5 4173.7 4197.6 4242.6 3995.9 4070.0 4094.2 4104.6 4209.1 4580.9 4618.1 4657.3 4666.2 4691.9 4698.9 4728.8 4820.4 4929.7 4974.4 4747.9 4770.5 4791.7 4898.7 4903.1 4504.6 4505.4 4510.7 4511.9 4537.9 4926.3 4932.8 4933.1 4936.7 4944.8 4728.9 4734.2 4754.6 4770.8 4807.6 4785.8 4809.5 4809.9 4935.1 4963.0 4735.7 4761.4 4772.9 4779.4 4783.2 4770.9 4775.4 4881.7 4901.5 4911.0 3264.7 3298.8 3326.0 3341.4 3348.8 2863.9 2908.5 2980.3 3028.9 3084.1 4469.8 4521.6 4524.3 4576.5 4617.0 4733.4 4785.6 4924.4 4936.7 4951.4 4428.0 4587.2 4606.3 4648.5 4757.2 4093.7 4112.2 4116.2 4125.8 4165.5 4966.7 5090.5 5177.0 5186.3 5208.1 4817.0 4837.1 4852.0 4949.7 4956.7 4977.2 5056.7 5069.8 5117.2 5207.7 4860.6 4880.6 4890.5 4922.5 4932.4 4841.7 4882.0 4961.2 4962.1 4982.1 4696.3 4815.2 4834.3 4919.4 4941.2 4688.1 4771.2 4852.3 4880.0 4901.3 4620.3 4670.0 4704.4 4823.0 4829.8 4850.1 4852.1 4890.4 4940.7 4951.0 4769.9 4770.6 4770.8 4799.1 4822.0
05:57:42 INFO - PROCESS | 3712 |
05:57:42 INFO - PROCESS | 3712 | [#1] ext.html Cycles:180 Average:56705.51 Median:36746.27 stddev:114488.42 (311.6%) stddev-sans-first:114731.87
05:57:42 INFO - PROCESS | 3712 | Values: 520.4 534.5 538.0 539.2 543.8 29535.4 29993.0 40515.2 43085.0 43513.1 40791.6 41697.0 41748.8 41978.6 41994.0 8147.7 8274.0 8316.5 8337.8 8555.7 6817.5 6966.8 9772.0 9959.4 9986.3 9975.7 10038.3 10103.5 10366.5 10384.6 10047.5 10065.9 10070.5 10268.5 10269.6 6882.9 7075.3 9802.9 10156.4 10161.6 37726.2 37969.0 38060.8 38194.2 38337.6 2933.9 3017.0 3033.9 3212.7 3238.4 29132.6 29319.6 29858.0 29980.6 30888.5 3185.1 3343.3 3389.6 3397.3 3491.7 330724.5 331790.0 334106.0 335932.0 338069.0 51178.0 51553.2 54219.5 54492.7 54660.7 27163.5 55332.2 56636.4 56825.4 56940.7 13807.9 14054.9 14505.8 14735.2 14758.9 9681.5 10897.6 11195.1 11241.2 11241.9 15054.7 17282.7 17590.6 17666.3 18014.0 6595.3 6669.9 6685.3 6704.9 6728.5 784.8 825.1 869.3 905.2 910.6 27816.7 28140.7 28881.6 28902.6 28977.7 918.6 935.1 941.8 999.6 1065.6 14501.9 15105.1 15276.5 15345.2 15465.1 492439.5 493455.0 500336.0 506568.0 506927.0 51134.5 51413.2 51511.7 51523.0 51585.7 1021.9 1036.6 1066.1 1156.7 1169.5 24254.0 29769.9 29946.7 30444.0 30768.5 47917.8 48128.0 48556.5 48882.3 49314.0 433546.0 434082.0 443934.0 450970.1 451032.0 22508.4 22873.0 22935.1 23142.2 23937.4 42613.6 42825.1 43506.0 43851.6 44328.8 36087.2 36986.4 37676.8 37898.4 38530.0 36506.1 37342.6 37506.8 37728.6 37750.2 34653.3 35303.0 36224.6 36293.8 36383.6 32833.0 32964.7 33206.3 33638.5 34160.8 25172.7 25534.5 25715.9 25756.2 26380.5
05:57:42 INFO - PROCESS | 3712 |
05:57:42 INFO - PROCESS | 3712 | [#2] jquery.html Cycles:180 Average:27201.17 Median:20260.66 stddev:75982.28 (375.0%) stddev-sans-first:76168.55
05:57:42 INFO - PROCESS | 3712 | Values: 285.6 297.4 307.9 308.7 315.2 12989.5 17231.4 17327.2 17685.5 18407.4 16596.5 16866.4 16914.2 17160.4 17372.0 5261.8 5460.1 5465.3 5469.2 5474.9 5277.8 5386.6 5399.7 5459.2 5555.2 5342.7 5348.7 5356.2 5377.2 5505.6 5254.4 5360.7 5369.9 5384.7 5462.8 4516.1 4596.4 4618.4 4621.8 4623.2 21537.7 21932.0 21948.2 22001.2 22339.8 1770.5 1832.4 2685.0 2704.4 2736.7 13259.7 13992.1 14213.4 14271.6 14779.2 1749.8 1782.8 1791.7 1804.6 1838.5 79233.0 81611.6 82148.0 82453.6 82498.2 8243.5 8254.4 8343.0 8396.2 8751.7 10137.3 10685.9 10970.2 11287.6 11402.8 6932.8 6991.8 7128.4 7252.4 7260.8 7392.8 7431.1 7459.0 7564.2 7594.8 5494.9 5547.3 5647.4 5661.0 5747.2 1426.3 1436.8 1452.4 1456.9 1507.3 302.9 312.5 318.3 334.4 885.3 9288.9 9508.4 9516.3 9659.0 10043.9 335.6 336.0 336.9 341.2 351.1 5045.7 5069.1 5101.4 5135.4 5216.5 446872.0 450934.0 451418.0 459370.0 474261.0 97266.0 98488.5 101010.0 101594.5 102156.0 349.6 353.2 354.3 355.5 880.8 16248.6 16259.8 16702.7 16870.4 16905.0 30267.7 40165.8 41720.0 44389.6 45181.8 58968.0 60206.0 60476.7 60535.4 61530.4 5719.0 6089.5 6131.4 6219.9 6895.6 12113.9 12248.6 12411.6 12567.3 12638.1 10227.9 10283.1 10350.9 10400.5 10614.1 9721.9 10074.7 10267.8 10297.5 10478.9 10413.9 10756.2 10816.7 10858.2 10984.7 5876.5 6034.5 6188.8 6206.9 6290.8 9505.0 10366.7 10524.3 10535.2 10781.8
05:57:42 INFO - PROCESS | 3712 |
05:57:42 INFO - PROCESS | 3712 | [#3] mootools.html Cycles:180 Average:4596.90 Median:5689.47 stddev:2569.21 (45.2%) stddev-sans-first:2563.61
05:57:42 INFO - PROCESS | 3712 | Values: 1182.6 1190.5 1217.9 1519.2 1549.4 6745.7 6870.3 7198.2 7203.8 7397.6 6545.8 6717.2 7086.8 7281.6 7325.0 1914.7 1926.1 1943.9 1944.6 1946.3 1856.1 1861.2 1908.4 1913.5 1933.6 1679.0 1733.6 1860.6 1877.9 1914.6 1832.5 1854.7 1859.3 1859.8 1862.9 1739.1 1767.5 1778.8 1809.6 1840.7 6995.8 7061.3 7103.1 7232.4 7237.2 4145.2 4251.1 4261.6 4309.8 4373.7 2848.2 2913.4 2917.6 2921.3 2978.6 898.2 929.4 932.9 934.2 934.9 9078.6 9179.5 9394.9 9401.3 9407.7 3623.3 3748.1 3750.1 3792.3 3792.3 7178.0 7214.5 7239.6 7380.9 7464.7 4290.6 4291.5 4292.2 4295.6 4300.3 4063.0 4066.2 4073.2 4076.9 4078.7 2769.7 2847.3 2863.5 2894.3 2904.6 815.6 822.6 825.2 827.4 831.7 746.2 753.8 794.3 799.1 803.0 4555.6 4791.0 4807.9 4974.8 5002.8 784.9 796.4 813.5 831.7 831.9 3039.5 3057.7 3129.5 3223.8 3291.9 8473.0 8522.8 8565.6 8601.0 8633.7 8460.7 8514.6 8680.2 8687.0 8698.5 6861.9 6892.8 6942.3 6942.7 6948.9 4619.7 4722.7 4745.2 4842.2 4843.2 7949.5 7975.6 8184.4 8194.1 8207.0 8014.3 8018.8 8099.8 8139.8 8203.4 6030.6 6122.0 6202.2 6222.8 6287.3 6525.0 6632.6 6738.6 6759.4 6762.7 5118.9 5757.6 5797.6 6266.2 6267.4 6129.6 6207.4 6210.4 6221.4 6292.6 5431.6 5447.7 5529.7 5535.8 5621.4 3957.9 4029.3 4085.3 4107.5 4148.3 4016.7 4028.0 4126.3 4151.5 4151.5
05:57:42 INFO - PROCESS | 3712 |
05:57:42 INFO - PROCESS | 3712 | [#4] prototype.html Cycles:180 Average:4253.80 Median:4944.13 stddev:1328.46 (26.9%) stddev-sans-first:1297.51
05:57:42 INFO - PROCESS | 3712 | Values: 236.3 241.9 242.1 243.5 244.4 4830.0 4924.7 4954.1 4964.9 5022.8 4752.4 4774.8 4908.4 4966.7 4992.2 3604.5 3608.6 3609.2 3611.6 3615.7 3515.4 3617.5 3619.2 3621.2 3645.3 3470.5 3484.5 3506.5 3561.2 3628.0 3535.6 3536.2 3537.6 3594.7 3623.8 3116.1 3124.7 3126.2 3133.4 3136.7 4829.4 4908.8 4918.3 4924.7 4928.6 4003.0 4103.4 4136.6 4211.1 4221.5 5182.4 5188.5 5195.3 5195.6 5195.9 3361.4 3398.3 3409.6 3416.9 3459.1 5279.4 5290.0 5298.5 5300.3 5304.7 3366.0 3437.7 3441.6 3444.1 3528.9 3437.4 3513.3 3515.6 3530.5 3535.0 4174.6 4176.7 4178.2 4181.3 4187.5 5238.6 5242.7 5252.4 5256.9 5268.8 1205.4 1229.5 1246.7 1259.3 1261.4 758.0 767.1 772.0 775.2 813.7 4789.9 4800.5 4831.0 4834.2 4877.5 5151.6 5152.0 5156.0 5158.2 5158.9 4786.8 4789.2 4848.2 4941.8 4942.5 2680.7 2812.0 2827.8 2832.0 2835.1 5497.0 5498.2 5504.8 5519.3 5523.0 5412.2 5422.1 5423.2 5430.1 5467.0 5211.0 5268.8 5364.4 5368.6 5373.0 5202.5 5248.2 5331.3 5340.9 5341.0 5268.3 5274.9 5278.1 5281.3 5283.7 5168.9 5355.2 5492.6 5493.2 5499.5 3506.2 3513.8 3515.5 3569.0 3610.2 5250.6 5294.3 5311.7 5314.8 5317.4 5060.7 5183.4 5188.9 5191.7 5194.4 4981.3 5098.6 5103.2 5214.8 5215.4 4945.7 4962.2 5049.8 5057.1 5059.8 4911.8 5060.3 5133.4 5137.2 5194.0 5205.1 5207.4 5210.2 5210.5 5214.4
05:57:42 INFO - PROCESS | 3712 |
05:57:42 INFO - PROCESS | 3712 | [#5] yui.html Cycles:180 Average:20874.81 Median:408.55 stddev:31329.37 (7668.4%) stddev-sans-first:31380.38
05:57:42 INFO - PROCESS | 3712 | Values: 630.1 631.3 701.5 707.8 708.3 13640.2 14358.7 14982.2 15395.6 15599.9 14318.9 14456.2 14488.3 14498.8 14521.3 635.1 635.4 636.1 636.7 642.3 515.9 527.6 628.1 639.0 643.6 675.1 677.4 678.2 683.7 696.4 692.6 694.1 694.5 695.6 695.6 402.9 407.5 409.6 490.8 497.9 13952.2 14203.9 14302.9 14323.6 14328.8 12151.9 12257.3 12357.8 12513.6 12663.3 12858.5 12869.4 12984.4 13087.3 13134.5 4248.2 4303.8 4317.9 4354.9 4387.5 79807.0 81161.6 81617.0 82086.6 82880.6 10123.7 10152.7 10170.9 10285.0 10410.7 18073.4 18182.3 18265.0 18380.0 18409.7 6795.4 6797.9 6838.3 6922.9 7040.9 3443.0 3447.8 3491.4 3555.1 3580.1 2778.0 2780.5 2785.1 2864.1 2872.5 474.4 483.9 486.5 491.4 496.4 518.7 635.6 650.6 653.1 673.3 9131.5 9193.2 9281.3 9369.4 9541.8 638.6 639.9 651.2 656.4 679.9 4339.7 4456.2 4503.2 4669.8 4683.5 89959.6 120409.4 121983.0 122839.4 124445.0 102179.0 103984.0 106615.0 107683.5 109130.5 94199.5 94252.1 94876.5 95866.0 96706.0 8440.6 8552.5 8642.4 8873.0 8955.1 54606.0 54979.7 55099.7 55693.7 56668.0 59298.4 64778.7 64888.4 66610.3 75952.0 12117.0 12263.4 12268.8 12592.4 12919.3 14018.2 14332.6 14845.9 14871.9 15012.1 13013.2 13080.2 13207.2 13296.9 13731.1 10884.0 12807.4 13317.0 13449.4 13692.8 12207.5 12774.6 13218.0 13252.0 13611.3 10965.2 11327.3 11372.9 11393.1 11594.5 10365.6 10845.3 10888.9 11089.7 11200.2
05:57:42 INFO - PROCESS | 3712 | -------- Summary: end --------
05:57:42 INFO - PROCESS | 3712 |
05:57:43 INFO - TEST-INFO | 3712: exit 0
05:57:43 INFO - TEST-OK | dromaeo_css | took 1120575ms
05:57:43 INFO - TEST-START | kraken
05:57:43 INFO - operating with platform_type : w7_
05:57:43 INFO - Initialising browser for kraken test...
05:57:43 INFO - TEST-INFO | started process 3628 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpjvf7xb\profile http://localhost:49889/getInfo.html)
05:57:48 INFO - PROCESS | 3628 | __metrics Screen width/height:1600/1200
05:57:48 INFO - PROCESS | 3628 | colorDepth:24
05:57:48 INFO - PROCESS | 3628 | Browser inner width/height: 1010/674
05:57:48 INFO - PROCESS | 3628 | __metrics
05:57:48 INFO - PROCESS | 3628 | 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"
05:57:48 INFO - PROCESS | 3628 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
05:57:49 INFO - PROCESS | 3628 | console.error:
05:57:49 INFO - PROCESS | 3628 | Message: Error: Connection closed before committing the transaction.
05:57:49 INFO - PROCESS | 3628 | Stack:
05:57:49 INFO - PROCESS | 3628 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:937:23
05:57:49 INFO - PROCESS | 3628 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7
05:57:49 INFO - PROCESS | 3628 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11
05:57:49 INFO - PROCESS | 3628 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7
05:57:49 INFO - PROCESS | 3628 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7
05:57:49 INFO - PROCESS | 3628 | TaskImpl_run@resource://gre/modules/Task.jsm:327:15
05:57:49 INFO - PROCESS | 3628 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9
05:57:49 INFO - PROCESS | 3628 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15
05:57:49 INFO - PROCESS | 3628 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5
05:57:49 INFO - PROCESS | 3628 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29
05:57:49 INFO - PROCESS | 3628 | Barrier/this.client.addBlocker/promise13.2%
05:58:47 INFO - TinderboxPrint: I/O read bytes / time
80,026,112 / 782,280
05:58:47 INFO - TinderboxPrint: I/O write bytes / time
476,247,552 / 3,460,790
05:58:47 INFO - TinderboxPrint: CPU idle
8,241.2 (86.8%)
05:58:47 INFO - TinderboxPrint: CPU user
1,212.8 (12.8%)
05:58:47 INFO - install - Wall time: 2s; CPU: 11.0%; Read bytes: 16384; Write bytes: 49907200; Read time: 1160; Write time: 38790
05:58:47 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 80009728; Write bytes: 403696128; Read time: 781120; Write time: 3051410
05:58:47 INFO - Running post-run listener: _upload_blobber_files
05:58:47 INFO - Blob upload gear active.
05:58:47 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir.
05:58:47 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
05:58:47 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\venv\\Scripts\\blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', 'C:\\slave\\test\\oauth.txt', '-b', 'Fx-Team', '-d', 'C:\\slave\\test\\build\\blobber_upload_dir', '--output-manifest', 'C:\\slave\\test\\build\\uploaded_files.json']
05:58:47 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\venv\Scripts\blobberc.py -u https://blobupload.elasticbeanstalk.com -a C:\slave\test\oauth.txt -b Fx-Team -d C:\slave\test\build\blobber_upload_dir --output-manifest C:\slave\test\build\uploaded_files.json
05:58:48 INFO - (blobuploader) - INFO - Open directory for files ...
05:58:48 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log ...
05:58:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
05:58:48 INFO - (blobuploader) - INFO - Uploading, attempt #1.
05:58:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded
05:58:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
05:58:49 INFO - (blobuploader) - INFO - Done attempting.
05:58:49 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log ...
05:58:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
05:58:49 INFO - (blobuploader) - INFO - Uploading, attempt #1.
05:58:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded
05:58:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
05:58:49 INFO - (blobuploader) - INFO - Done attempting.
05:58:49 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ...
05:58:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
05:58:49 INFO - (blobuploader) - INFO - Uploading, attempt #1.
05:58:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
05:58:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
05:58:57 INFO - (blobuploader) - INFO - Done attempting.
05:58:57 INFO - (blobuploader) - INFO - Iteration through files over.
05:58:57 INFO - Return code: 0
05:58:57 INFO - rmtree: C:\slave\test\build\uploaded_files.json
05:58:57 INFO - Using _rmtree_windows ...
05:58:57 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1
05:58:57 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7ff2b91a9195b6270cf81b03421f48dcb9069a98a85dad24d40915b10ddec1602db270d8c9d4f29f85bc645930820a3596cbb4244f1b9bafbb138b5f3b164386", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1898bdd251511dc356e37e88291d9050f15c2a9168c4d5c3ce7016c0018ea54cc49f4c9255404261c8aa49ceb348938bb38b18bf2b9abd9efce413977c8ea870", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cbafb47a1fd1747b004bda20e927f120f95ce2602a6d1c5ebfd2422cb3fc3cafb206549d8d89b913b7a1cbcb2c0f43b8e060a682c6e9f52828222f9ff450f510"}
05:58:57 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files
05:58:57 INFO - Writing to file C:\slave\test\properties\blobber_files
05:58:57 INFO - Contents:
05:58:57 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7ff2b91a9195b6270cf81b03421f48dcb9069a98a85dad24d40915b10ddec1602db270d8c9d4f29f85bc645930820a3596cbb4244f1b9bafbb138b5f3b164386", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1898bdd251511dc356e37e88291d9050f15c2a9168c4d5c3ce7016c0018ea54cc49f4c9255404261c8aa49ceb348938bb38b18bf2b9abd9efce413977c8ea870", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cbafb47a1fd1747b004bda20e927f120f95ce2602a6d1c5ebfd2422cb3fc3cafb206549d8d89b913b7a1cbcb2c0f43b8e060a682c6e9f52828222f9ff450f510"}
05:58:57 INFO - Running post-run listener: copy_logs_to_upload_dir
05:58:57 INFO - Copying logs to upload dir...
05:58:57 INFO - mkdir: C:\slave\test\build\upload\logs
05:58:57 INFO - Copying logs to upload dir...
05:58:57 INFO - Using _rmtree_windows ...
05:58:57 INFO - Using _rmtree_windows ...
05:58:57 INFO - Using _rmtree_windows ...
05:58:57 INFO - Using _rmtree_windows ...
05:58:57 INFO - Using _rmtree_windows ...
05:58:57 INFO - Using _rmtree_windows ...
05:58:57 INFO - Using _rmtree_windows ...
program finished with exit code 0
elapsedTime=1394.761000
========= master_lag: -0.27 =========
========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 14 secs) (at 2016-10-15 05:58:57.214028) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-15 05:58:57.219908) =========
'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-096
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-096
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-096
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7ff2b91a9195b6270cf81b03421f48dcb9069a98a85dad24d40915b10ddec1602db270d8c9d4f29f85bc645930820a3596cbb4244f1b9bafbb138b5f3b164386", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1898bdd251511dc356e37e88291d9050f15c2a9168c4d5c3ce7016c0018ea54cc49f4c9255404261c8aa49ceb348938bb38b18bf2b9abd9efce413977c8ea870", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cbafb47a1fd1747b004bda20e927f120f95ce2602a6d1c5ebfd2422cb3fc3cafb206549d8d89b913b7a1cbcb2c0f43b8e060a682c6e9f52828222f9ff450f510"}
build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476527404/firefox-52.0a1.en-US.win32.zip
program finished with exit code 0
elapsedTime=0.101000
build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476527404/firefox-52.0a1.en-US.win32.zip'
blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7ff2b91a9195b6270cf81b03421f48dcb9069a98a85dad24d40915b10ddec1602db270d8c9d4f29f85bc645930820a3596cbb4244f1b9bafbb138b5f3b164386", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1898bdd251511dc356e37e88291d9050f15c2a9168c4d5c3ce7016c0018ea54cc49f4c9255404261c8aa49ceb348938bb38b18bf2b9abd9efce413977c8ea870", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cbafb47a1fd1747b004bda20e927f120f95ce2602a6d1c5ebfd2422cb3fc3cafb206549d8d89b913b7a1cbcb2c0f43b8e060a682c6e9f52828222f9ff450f510"}'
========= master_lag: 0.20 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-15 05:58:57.525797) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-15 05:58:57.526154) =========
'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-096
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-096
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-096
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-15 05:58:57.639459) =========
========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-10-15 05:58:57.639800) =========
========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-10-15 05:58:58.053500) =========
========= Total master_lag: 0.08 =========