builder: fx-team_win7_ix_test-dromaeojs-pgo
slave: t-w732-ix-106
starttime: 1476858563.49
results: success (0)
buildid: 20161018232902
builduid: ef9cec1f5e5e4639a68e6ec9fe109a5e
revision: f52b380bc02e1022631ab8dcd43ed246930974c3
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-18 23:29:23.490756) =========
master: http://buildbot-master119.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-18 23:29:23.491133) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-18 23:29:23.491401) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-18 23:29:23.656206) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-18 23:29:23.656518) =========
'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-106
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-106
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-106
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
--23:29:24-- 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% 22.73 MB/s
23:29:24 (22.73 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=1.240000
========= master_lag: 0.03 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-18 23:29:24.923021) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 13 secs) (at 2016-10-18 23:29:24.923414) =========
'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-106
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-106
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-106
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=13.712000
========= master_lag: 0.00 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 13 secs) (at 2016-10-18 23:29:38.638343) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-18 23:29:38.638718) =========
'bash' '-c' u'python archiver_client.py mozharness --repo integration/fx-team --rev f52b380bc02e1022631ab8dcd43ed246930974c3 --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 f52b380bc02e1022631ab8dcd43ed246930974c3 --destination scripts --debug']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-106
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-106
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-106
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-18 23:29:38,792 truncating revision to first 12 chars
2016-10-18 23:29:38,792 Setting DEBUG logging.
2016-10-18 23:29:38,792 attempt 1/10
2016-10-18 23:29:38,792 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/f52b380bc02e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2016-10-18 23:29:39,786 unpacking tar archive at: fx-team-f52b380bc02e/testing/mozharness/
2016-10-18 23:29:39,911 skipping symlink on windows: configs/single_locale/linux32.py
program finished with exit code 0
elapsedTime=1.899000
========= master_lag: 0.05 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-18 23:29:40.582931) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-18 23:29:40.583259) =========
script_repo_revision: f52b380bc02e1022631ab8dcd43ed246930974c3
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-18 23:29:40.583655) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-18 23:29:40.583920) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-18 23:29:40.616677) =========
========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 8 secs) (at 2016-10-18 23:29:40.616965) =========
'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-106
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-106
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-106
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
23:29:40 INFO - MultiFileLogger online at 20161018 23:29:40 in C:\slave\test
23:29:40 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
23:29:40 INFO - Dumping config to C:\slave\test\logs\localconfig.json.
23:29:40 INFO - {'append_to_log': False,
23:29:40 INFO - 'base_work_dir': 'C:\\slave\\test',
23:29:40 INFO - 'blob_upload_branch': 'Fx-Team',
23:29:40 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt',
23:29:40 INFO - 'branch': 'Fx-Team',
23:29:40 INFO - 'buildbot_json_path': 'buildprops.json',
23:29:40 INFO - 'config_files': ('talos/windows_config.py',),
23:29:40 INFO - 'default_actions': ('clobber',
23:29:40 INFO - 'read-buildbot-config',
23:29:40 INFO - 'download-and-extract',
23:29:40 INFO - 'populate-webroot',
23:29:40 INFO - 'create-virtualenv',
23:29:40 INFO - 'install',
23:29:40 INFO - 'run-tests'),
23:29:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
23:29:40 INFO - 'download_minidump_stackwalk': True,
23:29:40 INFO - 'download_symbols': 'ondemand',
23:29:40 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python',
23:29:40 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'),
23:29:40 INFO - 'hg': 'c:/mozilla-build/hg/hg',
23:29:40 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python',
23:29:40 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'),
23:29:40 INFO - 'python': 'c:/mozilla-build/python27/python.exe',
23:29:40 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe',
23:29:40 INFO - 'C:/mozilla-build/tooltool.py'),
23:29:40 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe',
23:29:40 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')},
23:29:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
23:29:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
23:29:40 INFO - 'installer_path': 'installer.exe',
23:29:40 INFO - 'log_level': 'info',
23:29:40 INFO - 'log_name': 'talos',
23:29:40 INFO - 'log_to_console': True,
23:29:40 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe',
23:29:40 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe',
23:29:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest',
23:29:40 INFO - 'opt_config_files': (),
23:29:40 INFO - 'pip_index': False,
23:29:40 INFO - 'sps_profile': False,
23:29:40 INFO - 'sps_profile_interval': 0,
23:29:40 INFO - 'suite': 'dromaeojs',
23:29:40 INFO - 'system_bits': '32',
23:29:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
23:29:40 INFO - 'title': 'T-W732-IX-106',
23:29:40 INFO - 'use_talos_json': True,
23:29:40 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'),
23:29:40 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv',
23:29:40 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll',
23:29:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
23:29:40 INFO - 'work_dir': 'build'}
23:29:40 INFO - [mozharness: 2016-10-19 06:29:40.964000Z] Running clobber step.
23:29:40 INFO - Running pre-action listener: _resource_record_pre_action
23:29:40 INFO - Running main action method: clobber
23:29:40 INFO - rmtree: C:\slave\test\build
23:29:40 INFO - Using _rmtree_windows ...
23:29:41 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1
23:31:50 INFO - Running post-action listener: _resource_record_post_action
23:31:50 INFO - [mozharness: 2016-10-19 06:31:50.541000Z] Finished clobber step (success)
23:31:50 INFO - [mozharness: 2016-10-19 06:31:50.541000Z] Running read-buildbot-config step.
23:31:50 INFO - Running pre-action listener: _resource_record_pre_action
23:31:50 INFO - Running main action method: read_buildbot_config
23:31:50 INFO - Using buildbot properties:
23:31:50 INFO - {
23:31:50 INFO - "project": "",
23:31:50 INFO - "product": "firefox",
23:31:50 INFO - "script_repo_revision": "production",
23:31:50 INFO - "builddir": "fx-team_win7_ix_test-dromaeojs",
23:31:50 INFO - "repository": "",
23:31:50 INFO - "buildername": "Windows 7 32-bit fx-team pgo talos dromaeojs",
23:31:50 INFO - "buildid": "20161018232902",
23:31:50 INFO - "stage_platform": "win32-pgo",
23:31:50 INFO - "basedir": "C:\\slave\\test",
23:31:50 INFO - "buildnumber": 42,
23:31:50 INFO - "platform": "win7_ix",
23:31:50 INFO - "master": "http://buildbot-master119.bb.releng.scl3.mozilla.com:8201/",
23:31:50 INFO - "slavebuilddir": "test",
23:31:50 INFO - "branch": "fx-team",
23:31:50 INFO - "revision": "f52b380bc02e1022631ab8dcd43ed246930974c3",
23:31:50 INFO - "slavename": "t-w732-ix-106",
23:31:50 INFO - "builduid": "ef9cec1f5e5e4639a68e6ec9fe109a5e",
23:31:50 INFO - "repo_path": "integration/fx-team"
23:31:50 INFO - }
23:31:50 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476851407/firefox-52.0a1.en-US.win32.test_packages.json.
23:31:50 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476851407/firefox-52.0a1.en-US.win32.zip.
23:31:50 INFO - Running post-action listener: _resource_record_post_action
23:31:50 INFO - [mozharness: 2016-10-19 06:31:50.545000Z] Finished read-buildbot-config step (success)
23:31:50 INFO - [mozharness: 2016-10-19 06:31:50.545000Z] Running download-and-extract step.
23:31:50 INFO - Running pre-action listener: _resource_record_pre_action
23:31:50 INFO - Running main action method: download_and_extract
23:31:50 INFO - mkdir: C:\slave\test\build\tests
23:31: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')]}
23:31:50 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476851407/firefox-52.0a1.en-US.win32.test_packages.json
23:31:50 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476851407/firefox-52.0a1.en-US.win32.test_packages.json to C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json
23:31:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476851407/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
23:31:50 INFO - Downloaded 1218 bytes.
23:31:50 INFO - Reading from file C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json
23:31:50 INFO - Using the following test package requirements:
23:31:50 INFO - {u'common': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
23:31:50 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
23:31:50 INFO - u'firefox-52.0a1.en-US.win32.cppunittest.tests.zip'],
23:31:50 INFO - u'gtest': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
23:31:50 INFO - u'jittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
23:31:50 INFO - u'jsshell-win32.zip'],
23:31:50 INFO - u'mochitest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
23:31:50 INFO - u'firefox-52.0a1.en-US.win32.mochitest.tests.zip'],
23:31:50 INFO - u'mozbase': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
23:31:50 INFO - u'reftest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
23:31:50 INFO - u'firefox-52.0a1.en-US.win32.reftest.tests.zip'],
23:31:50 INFO - u'talos': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
23:31:50 INFO - u'firefox-52.0a1.en-US.win32.talos.tests.zip'],
23:31:50 INFO - u'web-platform': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
23:31:50 INFO - u'firefox-52.0a1.en-US.win32.web-platform.tests.zip'],
23:31:50 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
23:31:50 INFO - u'firefox-52.0a1.en-US.win32.xpcshell.tests.zip']}
23:31:50 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip'] for test suite category: common
23:31:50 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/1476851407/firefox-52.0a1.en-US.win32.common.tests.zip
23:31:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476851407/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1
23:31:50 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476851407/firefox-52.0a1.en-US.win32.common.tests.zip into memory
23:31:51 INFO - Http code: 200
23:31:51 INFO - via: 1.1 bb8a7ff26b131d51953dc2d3e19a2751.cloudfront.net (CloudFront)
23:31:51 INFO - x-amz-cf-id: euVWjOyay6xdl3ds72lxDUochUiA1Er8OKANvDRP1pSbN4C8NuB1kg==
23:31:51 INFO - x-amz-replication-status: COMPLETED
23:31:51 INFO - x-amz-version-id: 2or4QWOq3LFfDFQ5W73n5pnMPyCyLuVn
23:31:52 INFO - Expected file size: 17258265
23:31:52 INFO - Obtained file size: 17258265
23:32:18 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
23:32: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/1476851407/firefox-52.0a1.en-US.win32.common.tests.zip
23:32: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/1476851407/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1
23:32:18 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476851407/firefox-52.0a1.en-US.win32.common.tests.zip into memory
23:32:18 INFO - Http code: 200
23:32:18 INFO - via: 1.1 37af14ee951b12568cf1cc160d08abe6.cloudfront.net (CloudFront)
23:32:18 INFO - x-amz-cf-id: NthhXNR8wNhR3vkJRYQeZCv8qAE7CjKObfPih5TCxhdoSuiY9tvRzQ==
23:32:18 INFO - x-amz-replication-status: COMPLETED
23:32:18 INFO - x-amz-version-id: 2or4QWOq3LFfDFQ5W73n5pnMPyCyLuVn
23:32:19 INFO - Expected file size: 17258265
23:32:19 INFO - Obtained file size: 17258265
23:32:32 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/1476851407/firefox-52.0a1.en-US.win32.talos.tests.zip
23:32:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476851407/firefox-52.0a1.en-US.win32.talos.tests.zip'}, attempt #1
23:32:32 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476851407/firefox-52.0a1.en-US.win32.talos.tests.zip into memory
23:32:32 INFO - Http code: 200
23:32:32 INFO - via: 1.1 032d42677fbf05c24a4bb71369581429.cloudfront.net (CloudFront)
23:32:32 INFO - x-amz-cf-id: gcsLQiONFaerjGfJXa2gnCMeEXKUWoc7Ju9knoD9lJKjOhI4slEOhg==
23:32:32 INFO - x-amz-replication-status: COMPLETED
23:32:32 INFO - x-amz-version-id: 9UdSxG75YgvG4z.XNgZtgPLBjUHyUbzc
23:32:32 INFO - Expected file size: 14409028
23:32:32 INFO - Obtained file size: 14409028
23:32:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
23:32:43 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476851407/firefox-52.0a1.en-US.win32.zip
23:32:43 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476851407/firefox-52.0a1.en-US.win32.zip to C:\slave\test\build\firefox-52.0a1.en-US.win32.zip
23:32:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476851407/firefox-52.0a1.en-US.win32.zip', 'file_name': 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.zip'}, attempt #1
23:32:46 INFO - Downloaded 54339342 bytes.
23:32:46 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476851407/firefox-52.0a1.en-US.win32.zip
23:32:46 INFO - mkdir: C:\slave\test\properties
23:32:46 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url
23:32:46 INFO - Writing to file C:\slave\test\properties\build_url
23:32:46 INFO - Contents:
23:32:46 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476851407/firefox-52.0a1.en-US.win32.zip
23:32:46 INFO - Running post-action listener: _resource_record_post_action
23:32:46 INFO - Running post-action listener: set_extra_try_arguments
23:32:46 INFO - [mozharness: 2016-10-19 06:32:46.893000Z] Finished download-and-extract step (success)
23:32:46 INFO - [mozharness: 2016-10-19 06:32:46.893000Z] Running populate-webroot step.
23:32:46 INFO - Running pre-action listener: _resource_record_pre_action
23:32:46 INFO - Running main action method: populate_webroot
23:32:46 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']},
23:32:46 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']},
23:32:46 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome',
23:32:46 INFO - u'--tppagecycles',
23:32:46 INFO - u'7'],
23:32:46 INFO - u'tests': [u'tsvgm']}},
23:32:46 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'],
23:32:46 INFO - u'tests': [u'tresize', u'tcanvasmark']},
23:32:46 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']},
23:32:46 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'],
23:32:46 INFO - u'tests': [u'dromaeo_css', u'kraken']},
23:32:46 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']},
23:32:46 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
23:32:46 INFO - u'talos_options': [u'--disable-e10s'],
23:32:46 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
23:32:46 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
23:32:46 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
23:32:46 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
23:32:46 INFO - u'talos_options': [u'--disable-e10s'],
23:32:46 INFO - u'tests': [u'damp', u'tps']},
23:32:46 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
23:32:46 INFO - u'tests': [u'damp', u'tps']},
23:32:46 INFO - u'g3': {u'talos_options': [u'--disable-e10s'],
23:32:46 INFO - u'tests': [u'dromaeo_dom']},
23:32:46 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']},
23:32:46 INFO - u'g4': {u'talos_options': [u'--disable-e10s'],
23:32:46 INFO - u'tests': [u'basic_compositor_video']},
23:32:46 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']},
23:32:46 INFO - u'other': {u'talos_options': [u'--disable-e10s'],
23:32:46 INFO - u'tests': [u'a11yr',
23:32:46 INFO - u'ts_paint',
23:32:46 INFO - u'tpaint',
23:32:46 INFO - u'sessionrestore',
23:32:46 INFO - u'sessionrestore_no_auto_restore',
23:32:46 INFO - u'tabpaint']},
23:32:46 INFO - u'other-e10s': {u'tests': [u'a11yr',
23:32:46 INFO - u'ts_paint',
23:32:46 INFO - u'tpaint',
23:32:46 INFO - u'sessionrestore',
23:32:46 INFO - u'sessionrestore_no_auto_restore',
23:32:46 INFO - u'tabpaint']},
23:32:46 INFO - u'other-e10s_l64': {u'tests': [u'a11yr',
23:32:46 INFO - u'ts_paint',
23:32:46 INFO - u'tpaint',
23:32:46 INFO - u'sessionrestore',
23:32:46 INFO - u'sessionrestore_no_auto_restore',
23:32:46 INFO - u'tabpaint']},
23:32:46 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr',
23:32:46 INFO - u'ts_paint',
23:32:46 INFO - u'tpaint',
23:32:46 INFO - u'sessionrestore',
23:32:46 INFO - u'sessionrestore_no_auto_restore',
23:32:46 INFO - u'tabpaint']},
23:32:46 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'],
23:32:46 INFO - u'tests': [u'a11yr',
23:32:46 INFO - u'ts_paint',
23:32:46 INFO - u'tpaint',
23:32:46 INFO - u'sessionrestore',
23:32:46 INFO - u'sessionrestore_no_auto_restore',
23:32:46 INFO - u'tabpaint']},
23:32:46 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'],
23:32:46 INFO - u'tests': [u'a11yr',
23:32:46 INFO - u'ts_paint',
23:32:46 INFO - u'tpaint',
23:32:46 INFO - u'sessionrestore',
23:32:46 INFO - u'sessionrestore_no_auto_restore',
23:32:46 INFO - u'tabpaint']},
23:32:46 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'],
23:32:46 INFO - u'tests': [u'tsvgx',
23:32:46 INFO - u'tsvgr_opacity',
23:32:46 INFO - u'tart',
23:32:46 INFO - u'tscrollx',
23:32:46 INFO - u'cart']},
23:32:46 INFO - u'svgr-e10s': {u'tests': [u'tsvgx',
23:32:46 INFO - u'tsvgr_opacity',
23:32:46 INFO - u'tart',
23:32:46 INFO - u'tscrollx',
23:32:46 INFO - u'cart']},
23:32:46 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
23:32:46 INFO - u'talos_options': [u'--disable-e10s'],
23:32:46 INFO - u'tests': [u'tp5o']},
23:32:46 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
23:32:46 INFO - u'tests': [u'tp5o']},
23:32:46 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
23:32:46 INFO - u'talos_options': [u'--disable-e10s',
23:32:46 INFO - u'--xperf_path',
23:32:46 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
23:32:46 INFO - u'tests': [u'tp5n']},
23:32:46 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
23:32:46 INFO - u'talos_options': [u'--xperf_path',
23:32:46 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
23:32:46 INFO - u'tests': [u'tp5n']}},
23:32:46 INFO - u'talos.zip': {u'path': u'',
23:32:46 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}}
23:32:46 INFO - Running post-action listener: _resource_record_post_action
23:32:46 INFO - [mozharness: 2016-10-19 06:32:46.957000Z] Finished populate-webroot step (success)
23:32:46 INFO - [mozharness: 2016-10-19 06:32:46.957000Z] Running create-virtualenv step.
23:32:46 INFO - Running pre-action listener: _resource_record_pre_action
23:32:46 INFO - Running main action method: create_virtualenv
23:32:46 INFO - Creating virtualenv C:\slave\test\build/venv
23:32:46 INFO - mkdir: C:\slave\test\build\venv\Scripts
23:32:46 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll
23:32:46 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'!
23:32:46 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
23:32:46 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv
23:32:58 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe
23:33:02 INFO - Installing distribute......................................................................................................................................................................................done.
23:33:03 INFO - Return code: 0
23:33:03 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version']
23:33:03 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version
23:33:03 INFO - Reading from file tmpfile_stdout
23:33:03 INFO - Output received:
23:33:03 INFO - pip 0.8.2 from c:\slave\test\build\venv\lib\site-packages\pip-0.8.2-py2.7.egg (python 2.7)
23:33:03 INFO - Using _rmtree_windows ...
23:33:03 INFO - Using _rmtree_windows ...
23:33:03 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv
23:33:03 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')]}
23:33:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
23:33:03 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')]}
23:33:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
23:33:03 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 0x018B8640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018EFE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01832B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01909E90>, '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 0x0124A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01975520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-106', 'USERDOMAIN': 'T-W732-IX-106', '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-106', '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
23:33:03 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
23:33:03 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
23:33:03 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
23:33:03 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
23:33:03 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
23:33:03 INFO - 'COMPUTERNAME': 'T-W732-IX-106',
23:33:03 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
23:33:03 INFO - 'CYGWINBASE': 'C:\\cygwin',
23:33:03 INFO - 'DCLOCATION': 'SCL3',
23:33:03 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
23:33:03 INFO - 'FP_NO_HOST_CHECK': 'NO',
23:33:03 INFO - 'HOMEDRIVE': 'C:',
23:33:03 INFO - 'HOMEPATH': '\\Users\\cltbld',
23:33:03 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
23:33:03 INFO - 'KTS_VERSION': '1.19c',
23:33:03 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
23:33:03 INFO - 'LOGONSERVER': '\\\\T-W732-IX-106',
23:33:03 INFO - 'MONDIR': 'C:\\Monitor_config\\',
23:33:03 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
23:33:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
23:33:03 INFO - 'MOZ_NO_REMOTE': '1',
23:33:03 INFO - 'NO_EM_RESTART': '1',
23:33:03 INFO - 'NUMBER_OF_PROCESSORS': '8',
23:33:03 INFO - 'OS': 'Windows_NT',
23:33:03 INFO - 'OURDRIVE': 'C:',
23:33:03 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;',
23:33:03 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
23:33:03 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
23:33:03 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
23:33:03 INFO - 'PROCESSOR_LEVEL': '6',
23:33:03 INFO - 'PROCESSOR_REVISION': '1e05',
23:33:03 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
23:33:03 INFO - 'PROGRAMFILES': 'C:\\Program Files',
23:33:03 INFO - 'PROMPT': '$P$G',
23:33:03 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
23:33:03 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
23:33:03 INFO - 'PUBLIC': 'C:\\Users\\Public',
23:33:03 INFO - 'PWD': 'C:\\slave\\test',
23:33:03 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
23:33:03 INFO - 'SLAVEDIR': 'C:\\slave\\',
23:33:03 INFO - 'SYSTEMDRIVE': 'C:',
23:33:03 INFO - 'SYSTEMROOT': 'C:\\windows',
23:33:03 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
23:33:03 INFO - 'TEST1': 'testie',
23:33:03 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
23:33:03 INFO - 'USERDOMAIN': 'T-W732-IX-106',
23:33:03 INFO - 'USERNAME': 'cltbld',
23:33:03 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
23:33:03 INFO - 'WINDIR': 'C:\\windows',
23:33:03 INFO - 'WINDOWS_TRACING_FLAGS': '3',
23:33:03 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
23:33:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
23:33:04 INFO - Ignoring indexes: http://pypi.python.org/simple/
23:33:04 INFO - Downloading/unpacking pip>=1.5
23:33:08 INFO - Running setup.py egg_info for package pip
23:33:08 INFO - warning: no files found matching 'pip\cacert.pem'
23:33:08 INFO - warning: no files found matching '*.html' under directory 'docs'
23:33:08 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
23:33:08 INFO - no previously-included directories found matching 'docs\_build\_sources'
23:33:08 INFO - Installing collected packages: pip
23:33:08 INFO - Found existing installation: pip 0.8.2
23:33:08 INFO - Uninstalling pip:
23:33:08 INFO - Successfully uninstalled pip
23:33:08 INFO - Running setup.py install for pip
23:33:08 INFO - warning: no files found matching 'pip\cacert.pem'
23:33:08 INFO - warning: no files found matching '*.html' under directory 'docs'
23:33:08 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
23:33:08 INFO - no previously-included directories found matching 'docs\_build\_sources'
23:33:08 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts
23:33:08 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts
23:33:08 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts
23:33:08 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts
23:33:08 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts
23:33:08 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts
23:33:08 INFO - Successfully installed pip
23:33:08 INFO - Cleaning up...
23:33:08 INFO - Return code: 0
23:33:08 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv
23:33:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
23:33:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
23:33:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
23:33:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
23:33:08 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x018B8640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018EFE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01832B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01909E90>, '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 0x0124A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01975520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-106', 'USERDOMAIN': 'T-W732-IX-106', '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-106', '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
23:33:08 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in C:\slave\test\build
23:33:08 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1
23:33:08 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
23:33:08 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
23:33:08 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
23:33:08 INFO - 'COMPUTERNAME': 'T-W732-IX-106',
23:33:08 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
23:33:08 INFO - 'CYGWINBASE': 'C:\\cygwin',
23:33:08 INFO - 'DCLOCATION': 'SCL3',
23:33:08 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
23:33:08 INFO - 'FP_NO_HOST_CHECK': 'NO',
23:33:08 INFO - 'HOMEDRIVE': 'C:',
23:33:08 INFO - 'HOMEPATH': '\\Users\\cltbld',
23:33:08 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
23:33:08 INFO - 'KTS_VERSION': '1.19c',
23:33:08 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
23:33:08 INFO - 'LOGONSERVER': '\\\\T-W732-IX-106',
23:33:08 INFO - 'MONDIR': 'C:\\Monitor_config\\',
23:33:08 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
23:33:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
23:33:08 INFO - 'MOZ_NO_REMOTE': '1',
23:33:08 INFO - 'NO_EM_RESTART': '1',
23:33:08 INFO - 'NUMBER_OF_PROCESSORS': '8',
23:33:08 INFO - 'OS': 'Windows_NT',
23:33:08 INFO - 'OURDRIVE': 'C:',
23:33:08 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
23:33:08 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
23:33:08 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
23:33:08 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
23:33:08 INFO - 'PROCESSOR_LEVEL': '6',
23:33:08 INFO - 'PROCESSOR_REVISION': '1e05',
23:33:08 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
23:33:08 INFO - 'PROGRAMFILES': 'C:\\Program Files',
23:33:08 INFO - 'PROMPT': '$P$G',
23:33:08 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
23:33:08 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
23:33:08 INFO - 'PUBLIC': 'C:\\Users\\Public',
23:33:08 INFO - 'PWD': 'C:\\slave\\test',
23:33:08 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
23:33:08 INFO - 'SLAVEDIR': 'C:\\slave\\',
23:33:08 INFO - 'SYSTEMDRIVE': 'C:',
23:33:08 INFO - 'SYSTEMROOT': 'C:\\windows',
23:33:08 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
23:33:08 INFO - 'TEST1': 'testie',
23:33:08 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
23:33:08 INFO - 'USERDOMAIN': 'T-W732-IX-106',
23:33:08 INFO - 'USERNAME': 'cltbld',
23:33:08 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
23:33:08 INFO - 'WINDIR': 'C:\\windows',
23:33:08 INFO - 'WINDOWS_TRACING_FLAGS': '3',
23:33:08 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
23:33:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
23:33:11 INFO - Ignoring indexes: https://pypi.python.org/simple/
23:33:11 INFO - Downloading/unpacking psutil>=3.1.1
23:33:11 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
23:33:11 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
23:33:12 INFO - Installing collected packages: psutil
23:33:12 INFO - Successfully installed psutil
23:33:12 INFO - Cleaning up...
23:33:12 INFO - Return code: 0
23:33:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv
23:33:12 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')]}
23:33:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
23:33:12 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')]}
23:33:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
23:33:12 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 0x018B8640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018EFE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01832B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01909E90>, '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 0x0124A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01975520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-106', 'USERDOMAIN': 'T-W732-IX-106', '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-106', '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
23:33:12 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
23:33:12 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
23:33:12 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
23:33:12 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
23:33:12 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
23:33:12 INFO - 'COMPUTERNAME': 'T-W732-IX-106',
23:33:12 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
23:33:12 INFO - 'CYGWINBASE': 'C:\\cygwin',
23:33:12 INFO - 'DCLOCATION': 'SCL3',
23:33:12 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
23:33:12 INFO - 'FP_NO_HOST_CHECK': 'NO',
23:33:12 INFO - 'HOMEDRIVE': 'C:',
23:33:12 INFO - 'HOMEPATH': '\\Users\\cltbld',
23:33:12 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
23:33:12 INFO - 'KTS_VERSION': '1.19c',
23:33:12 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
23:33:12 INFO - 'LOGONSERVER': '\\\\T-W732-IX-106',
23:33:12 INFO - 'MONDIR': 'C:\\Monitor_config\\',
23:33:12 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
23:33:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
23:33:12 INFO - 'MOZ_NO_REMOTE': '1',
23:33:12 INFO - 'NO_EM_RESTART': '1',
23:33:12 INFO - 'NUMBER_OF_PROCESSORS': '8',
23:33:12 INFO - 'OS': 'Windows_NT',
23:33:12 INFO - 'OURDRIVE': 'C:',
23:33:12 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
23:33:12 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
23:33:12 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
23:33:12 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
23:33:12 INFO - 'PROCESSOR_LEVEL': '6',
23:33:12 INFO - 'PROCESSOR_REVISION': '1e05',
23:33:12 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
23:33:12 INFO - 'PROGRAMFILES': 'C:\\Program Files',
23:33:12 INFO - 'PROMPT': '$P$G',
23:33:12 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
23:33:12 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
23:33:12 INFO - 'PUBLIC': 'C:\\Users\\Public',
23:33:12 INFO - 'PWD': 'C:\\slave\\test',
23:33:12 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
23:33:12 INFO - 'SLAVEDIR': 'C:\\slave\\',
23:33:12 INFO - 'SYSTEMDRIVE': 'C:',
23:33:12 INFO - 'SYSTEMROOT': 'C:\\windows',
23:33:12 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
23:33:12 INFO - 'TEST1': 'testie',
23:33:12 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
23:33:12 INFO - 'USERDOMAIN': 'T-W732-IX-106',
23:33:12 INFO - 'USERNAME': 'cltbld',
23:33:12 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
23:33:12 INFO - 'WINDIR': 'C:\\windows',
23:33:12 INFO - 'WINDOWS_TRACING_FLAGS': '3',
23:33:12 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
23:33:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
23:33:12 INFO - Ignoring indexes: https://pypi.python.org/simple/
23:33:12 INFO - Downloading/unpacking mozsystemmonitor==0.3
23:33:12 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
23:33:12 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
23:33:13 INFO - Downloading mozsystemmonitor-0.3.tar.gz
23:33:13 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor
23:33:13 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)
23:33:13 INFO - Installing collected packages: mozsystemmonitor
23:33:13 INFO - Running setup.py install for mozsystemmonitor
23:33:14 INFO - Successfully installed mozsystemmonitor
23:33:14 INFO - Cleaning up...
23:33:14 INFO - Return code: 0
23:33:14 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv
23:33:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
23:33:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
23:33:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
23:33:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
23:33:14 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 0x018B8640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018EFE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01832B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01909E90>, '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 0x0124A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01975520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-106', 'USERDOMAIN': 'T-W732-IX-106', '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-106', '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
23:33:14 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
23:33:14 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
23:33:14 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
23:33:14 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
23:33:14 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
23:33:14 INFO - 'COMPUTERNAME': 'T-W732-IX-106',
23:33:14 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
23:33:14 INFO - 'CYGWINBASE': 'C:\\cygwin',
23:33:14 INFO - 'DCLOCATION': 'SCL3',
23:33:14 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
23:33:14 INFO - 'FP_NO_HOST_CHECK': 'NO',
23:33:14 INFO - 'HOMEDRIVE': 'C:',
23:33:14 INFO - 'HOMEPATH': '\\Users\\cltbld',
23:33:14 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
23:33:14 INFO - 'KTS_VERSION': '1.19c',
23:33:14 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
23:33:14 INFO - 'LOGONSERVER': '\\\\T-W732-IX-106',
23:33:14 INFO - 'MONDIR': 'C:\\Monitor_config\\',
23:33:14 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
23:33:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
23:33:14 INFO - 'MOZ_NO_REMOTE': '1',
23:33:14 INFO - 'NO_EM_RESTART': '1',
23:33:14 INFO - 'NUMBER_OF_PROCESSORS': '8',
23:33:14 INFO - 'OS': 'Windows_NT',
23:33:14 INFO - 'OURDRIVE': 'C:',
23:33:14 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
23:33:14 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
23:33:14 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
23:33:14 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
23:33:14 INFO - 'PROCESSOR_LEVEL': '6',
23:33:14 INFO - 'PROCESSOR_REVISION': '1e05',
23:33:14 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
23:33:14 INFO - 'PROGRAMFILES': 'C:\\Program Files',
23:33:14 INFO - 'PROMPT': '$P$G',
23:33:14 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
23:33:14 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
23:33:14 INFO - 'PUBLIC': 'C:\\Users\\Public',
23:33:14 INFO - 'PWD': 'C:\\slave\\test',
23:33:14 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
23:33:14 INFO - 'SLAVEDIR': 'C:\\slave\\',
23:33:14 INFO - 'SYSTEMDRIVE': 'C:',
23:33:14 INFO - 'SYSTEMROOT': 'C:\\windows',
23:33:14 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
23:33:14 INFO - 'TEST1': 'testie',
23:33:14 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
23:33:14 INFO - 'USERDOMAIN': 'T-W732-IX-106',
23:33:14 INFO - 'USERNAME': 'cltbld',
23:33:14 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
23:33:14 INFO - 'WINDIR': 'C:\\windows',
23:33:14 INFO - 'WINDOWS_TRACING_FLAGS': '3',
23:33:14 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
23:33:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
23:33:14 INFO - Ignoring indexes: https://pypi.python.org/simple/
23:33:14 INFO - Downloading/unpacking jsonschema==2.5.1
23:33:14 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
23:33:14 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
23:33:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl
23:33:15 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1)
23:33:15 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
23:33:15 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
23:33:16 INFO - Downloading functools32-3.2.3-2.tar.gz
23:33:16 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32
23:33:16 INFO - warning: no files found matching '*.txt'
23:33:16 INFO - no previously-included directories found matching 'build'
23:33:16 INFO - no previously-included directories found matching 'dist'
23:33:16 INFO - no previously-included directories found matching '.git*'
23:33:16 INFO - Installing collected packages: jsonschema, functools32
23:33:17 INFO - Running setup.py install for functools32
23:33:17 INFO - warning: no files found matching '*.txt'
23:33:17 INFO - no previously-included directories found matching 'build'
23:33:17 INFO - no previously-included directories found matching 'dist'
23:33:17 INFO - no previously-included directories found matching '.git*'
23:33:17 INFO - Successfully installed jsonschema functools32
23:33:17 INFO - Cleaning up...
23:33:17 INFO - Return code: 0
23:33:17 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv
23:33:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
23:33:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
23:33:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
23:33:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
23:33:17 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 0x018B8640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018EFE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01832B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01909E90>, '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 0x0124A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01975520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-106', 'USERDOMAIN': 'T-W732-IX-106', '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-106', '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
23:33:17 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
23:33:17 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
23:33:17 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
23:33:17 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
23:33:17 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
23:33:17 INFO - 'COMPUTERNAME': 'T-W732-IX-106',
23:33:17 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
23:33:17 INFO - 'CYGWINBASE': 'C:\\cygwin',
23:33:17 INFO - 'DCLOCATION': 'SCL3',
23:33:17 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
23:33:17 INFO - 'FP_NO_HOST_CHECK': 'NO',
23:33:17 INFO - 'HOMEDRIVE': 'C:',
23:33:17 INFO - 'HOMEPATH': '\\Users\\cltbld',
23:33:17 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
23:33:17 INFO - 'KTS_VERSION': '1.19c',
23:33:17 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
23:33:17 INFO - 'LOGONSERVER': '\\\\T-W732-IX-106',
23:33:17 INFO - 'MONDIR': 'C:\\Monitor_config\\',
23:33:17 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
23:33:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
23:33:17 INFO - 'MOZ_NO_REMOTE': '1',
23:33:17 INFO - 'NO_EM_RESTART': '1',
23:33:17 INFO - 'NUMBER_OF_PROCESSORS': '8',
23:33:17 INFO - 'OS': 'Windows_NT',
23:33:17 INFO - 'OURDRIVE': 'C:',
23:33:17 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;',
23:33:17 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
23:33:17 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
23:33:17 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
23:33:17 INFO - 'PROCESSOR_LEVEL': '6',
23:33:17 INFO - 'PROCESSOR_REVISION': '1e05',
23:33:17 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
23:33:17 INFO - 'PROGRAMFILES': 'C:\\Program Files',
23:33:17 INFO - 'PROMPT': '$P$G',
23:33:17 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
23:33:17 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
23:33:17 INFO - 'PUBLIC': 'C:\\Users\\Public',
23:33:17 INFO - 'PWD': 'C:\\slave\\test',
23:33:17 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
23:33:17 INFO - 'SLAVEDIR': 'C:\\slave\\',
23:33:17 INFO - 'SYSTEMDRIVE': 'C:',
23:33:17 INFO - 'SYSTEMROOT': 'C:\\windows',
23:33:17 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
23:33:17 INFO - 'TEST1': 'testie',
23:33:17 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
23:33:17 INFO - 'USERDOMAIN': 'T-W732-IX-106',
23:33:17 INFO - 'USERNAME': 'cltbld',
23:33:17 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
23:33:17 INFO - 'WINDIR': 'C:\\windows',
23:33:17 INFO - 'WINDOWS_TRACING_FLAGS': '3',
23:33:17 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
23:33:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
23:33:18 INFO - Ignoring indexes: https://pypi.python.org/simple/
23:33:18 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages
23:33:18 INFO - Cleaning up...
23:33:18 INFO - Return code: 0
23:33:18 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv
23:33: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')]}
23:33:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
23:33: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')]}
23:33:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
23:33:18 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 0x018B8640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018EFE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01832B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01909E90>, '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 0x0124A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01975520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-106', 'USERDOMAIN': 'T-W732-IX-106', '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-106', '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
23:33:18 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
23:33:18 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
23:33:18 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
23:33:18 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
23:33:18 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
23:33:18 INFO - 'COMPUTERNAME': 'T-W732-IX-106',
23:33:18 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
23:33:18 INFO - 'CYGWINBASE': 'C:\\cygwin',
23:33:18 INFO - 'DCLOCATION': 'SCL3',
23:33:18 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
23:33:18 INFO - 'FP_NO_HOST_CHECK': 'NO',
23:33:18 INFO - 'HOMEDRIVE': 'C:',
23:33:18 INFO - 'HOMEPATH': '\\Users\\cltbld',
23:33:18 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
23:33:18 INFO - 'KTS_VERSION': '1.19c',
23:33:18 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
23:33:18 INFO - 'LOGONSERVER': '\\\\T-W732-IX-106',
23:33:18 INFO - 'MONDIR': 'C:\\Monitor_config\\',
23:33:18 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
23:33:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
23:33:18 INFO - 'MOZ_NO_REMOTE': '1',
23:33:18 INFO - 'NO_EM_RESTART': '1',
23:33:18 INFO - 'NUMBER_OF_PROCESSORS': '8',
23:33:18 INFO - 'OS': 'Windows_NT',
23:33:18 INFO - 'OURDRIVE': 'C:',
23:33:18 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;',
23:33:18 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
23:33:18 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
23:33:18 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
23:33:18 INFO - 'PROCESSOR_LEVEL': '6',
23:33:18 INFO - 'PROCESSOR_REVISION': '1e05',
23:33:18 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
23:33:18 INFO - 'PROGRAMFILES': 'C:\\Program Files',
23:33:18 INFO - 'PROMPT': '$P$G',
23:33:18 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
23:33:18 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
23:33:18 INFO - 'PUBLIC': 'C:\\Users\\Public',
23:33:18 INFO - 'PWD': 'C:\\slave\\test',
23:33:18 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
23:33:18 INFO - 'SLAVEDIR': 'C:\\slave\\',
23:33:18 INFO - 'SYSTEMDRIVE': 'C:',
23:33:18 INFO - 'SYSTEMROOT': 'C:\\windows',
23:33:18 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
23:33:18 INFO - 'TEST1': 'testie',
23:33:18 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
23:33:18 INFO - 'USERDOMAIN': 'T-W732-IX-106',
23:33:18 INFO - 'USERNAME': 'cltbld',
23:33:18 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
23:33:18 INFO - 'WINDIR': 'C:\\windows',
23:33:18 INFO - 'WINDOWS_TRACING_FLAGS': '3',
23:33:18 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
23:33:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
23:33:18 INFO - Ignoring indexes: https://pypi.python.org/simple/
23:33:18 INFO - Downloading/unpacking blobuploader==1.2.4
23:33:18 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
23:33:18 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
23:33:19 INFO - Downloading blobuploader-1.2.4.tar.gz
23:33:19 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader
23:33:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4)
23:33:19 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
23:33:19 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
23:33:19 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests
23:33:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4)
23:33:20 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
23:33:20 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
23:33:20 INFO - Downloading docopt-0.6.1.tar.gz
23:33:20 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt
23:33:20 INFO - Installing collected packages: blobuploader, requests, docopt
23:33:20 INFO - Running setup.py install for blobuploader
23:33:20 INFO - Running setup.py install for requests
23:33:21 INFO - Running setup.py install for docopt
23:33:21 INFO - Successfully installed blobuploader requests docopt
23:33:21 INFO - Cleaning up...
23:33:22 INFO - Return code: 0
23:33:22 INFO - Installing None into virtualenv C:\slave\test\build/venv
23:33:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
23:33:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
23:33:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
23:33:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
23:33:22 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 0x018B8640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018EFE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01832B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01909E90>, '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 0x0124A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01975520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-106', 'USERDOMAIN': 'T-W732-IX-106', '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-106', '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
23:33:22 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
23:33:22 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
23:33:22 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
23:33:22 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
23:33:22 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
23:33:22 INFO - 'COMPUTERNAME': 'T-W732-IX-106',
23:33:22 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
23:33:22 INFO - 'CYGWINBASE': 'C:\\cygwin',
23:33:22 INFO - 'DCLOCATION': 'SCL3',
23:33:22 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
23:33:22 INFO - 'FP_NO_HOST_CHECK': 'NO',
23:33:22 INFO - 'HOMEDRIVE': 'C:',
23:33:22 INFO - 'HOMEPATH': '\\Users\\cltbld',
23:33:22 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
23:33:22 INFO - 'KTS_VERSION': '1.19c',
23:33:22 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
23:33:22 INFO - 'LOGONSERVER': '\\\\T-W732-IX-106',
23:33:22 INFO - 'MONDIR': 'C:\\Monitor_config\\',
23:33:22 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
23:33:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
23:33:22 INFO - 'MOZ_NO_REMOTE': '1',
23:33:22 INFO - 'NO_EM_RESTART': '1',
23:33:22 INFO - 'NUMBER_OF_PROCESSORS': '8',
23:33:22 INFO - 'OS': 'Windows_NT',
23:33:22 INFO - 'OURDRIVE': 'C:',
23:33:22 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;',
23:33:22 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
23:33:22 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
23:33:22 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
23:33:22 INFO - 'PROCESSOR_LEVEL': '6',
23:33:22 INFO - 'PROCESSOR_REVISION': '1e05',
23:33:22 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
23:33:22 INFO - 'PROGRAMFILES': 'C:\\Program Files',
23:33:22 INFO - 'PROMPT': '$P$G',
23:33:22 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
23:33:22 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
23:33:22 INFO - 'PUBLIC': 'C:\\Users\\Public',
23:33:22 INFO - 'PWD': 'C:\\slave\\test',
23:33:22 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
23:33:22 INFO - 'SLAVEDIR': 'C:\\slave\\',
23:33:22 INFO - 'SYSTEMDRIVE': 'C:',
23:33:22 INFO - 'SYSTEMROOT': 'C:\\windows',
23:33:22 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
23:33:22 INFO - 'TEST1': 'testie',
23:33:22 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
23:33:22 INFO - 'USERDOMAIN': 'T-W732-IX-106',
23:33:22 INFO - 'USERNAME': 'cltbld',
23:33:22 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
23:33:22 INFO - 'WINDIR': 'C:\\windows',
23:33:22 INFO - 'WINDOWS_TRACING_FLAGS': '3',
23:33:22 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
23:33:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
23:33:23 INFO - Ignoring indexes: https://pypi.python.org/simple/
23:33:23 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
23:33:23 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kh3lpo-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
23:33:23 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
23:33:23 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ni3dtm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
23:33:23 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
23:33:23 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-map9oi-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
23:33:23 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
23:33:23 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-arnwrx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
23:33:23 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
23:33:23 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-8ukgkn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
23:33:23 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
23:33:23 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gnflt1-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
23:33:24 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
23:33:24 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-2ls2bm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
23:33:24 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
23:33:24 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zrjf0z-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
23:33:24 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
23:33:24 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bkdttu-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
23:33:24 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
23:33:24 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mwlodr-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
23:33:24 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
23:33:24 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qfuhqt-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
23:33:24 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
23:33:24 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-5nkgjw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
23:33:24 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
23:33:24 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-iymj9j-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
23:33:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
23:33:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vl4m2j-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
23:33:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
23:33:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-udcmkq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
23:33:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
23:33:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fbotw6-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
23:33:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
23:33:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qg9m5w-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
23:33:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
23:33:25 INFO - Running setup.py install for manifestparser
23:33:25 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts
23:33:25 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts
23:33:26 INFO - Running setup.py install for mozcrash
23:33:26 INFO - Running setup.py install for mozdebug
23:33:26 INFO - Running setup.py install for mozdevice
23:33:27 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts
23:33:27 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts
23:33:27 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts
23:33:27 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts
23:33:27 INFO - Running setup.py install for mozfile
23:33:27 INFO - Running setup.py install for mozhttpd
23:33:28 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts
23:33:28 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts
23:33:28 INFO - Running setup.py install for mozinfo
23:33:28 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts
23:33:28 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts
23:33:28 INFO - Running setup.py install for mozInstall
23:33:28 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts
23:33:28 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts
23:33:28 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts
23:33:28 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts
23:33:28 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts
23:33:28 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts
23:33:28 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts
23:33:28 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts
23:33:29 INFO - Running setup.py install for mozleak
23:33:29 INFO - Running setup.py install for mozlog
23:33:29 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts
23:33:29 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts
23:33:30 INFO - Running setup.py install for moznetwork
23:33:30 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts
23:33:30 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts
23:33:30 INFO - Running setup.py install for mozprocess
23:33:31 INFO - Running setup.py install for mozprofile
23:33:31 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts
23:33:31 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts
23:33:31 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts
23:33:31 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts
23:33:31 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts
23:33:31 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts
23:33:32 INFO - Running setup.py install for mozrunner
23:33:32 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts
23:33:32 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts
23:33:32 INFO - Running setup.py install for mozscreenshot
23:33:33 INFO - Running setup.py install for moztest
23:33:33 INFO - Running setup.py install for mozversion
23:33:33 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts
23:33:33 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts
23:33:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion
23:33:33 INFO - Cleaning up...
23:33:33 INFO - Return code: 0
23:33:33 INFO - Installing None into virtualenv C:\slave\test\build/venv
23:33: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')]}
23:33:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
23:33: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')]}
23:33:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
23:33:33 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 0x018B8640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018EFE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01832B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01909E90>, '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 0x0124A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01975520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-106', 'USERDOMAIN': 'T-W732-IX-106', '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-106', '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
23:33:33 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
23:33:33 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
23:33:33 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
23:33:33 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
23:33:33 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
23:33:33 INFO - 'COMPUTERNAME': 'T-W732-IX-106',
23:33:33 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
23:33:33 INFO - 'CYGWINBASE': 'C:\\cygwin',
23:33:33 INFO - 'DCLOCATION': 'SCL3',
23:33:33 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
23:33:33 INFO - 'FP_NO_HOST_CHECK': 'NO',
23:33:33 INFO - 'HOMEDRIVE': 'C:',
23:33:33 INFO - 'HOMEPATH': '\\Users\\cltbld',
23:33:33 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
23:33:33 INFO - 'KTS_VERSION': '1.19c',
23:33:33 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
23:33:33 INFO - 'LOGONSERVER': '\\\\T-W732-IX-106',
23:33:33 INFO - 'MONDIR': 'C:\\Monitor_config\\',
23:33:33 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
23:33:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
23:33:33 INFO - 'MOZ_NO_REMOTE': '1',
23:33:33 INFO - 'NO_EM_RESTART': '1',
23:33:33 INFO - 'NUMBER_OF_PROCESSORS': '8',
23:33:33 INFO - 'OS': 'Windows_NT',
23:33:33 INFO - 'OURDRIVE': 'C:',
23:33: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;',
23:33:33 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
23:33:33 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
23:33:33 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
23:33:33 INFO - 'PROCESSOR_LEVEL': '6',
23:33:33 INFO - 'PROCESSOR_REVISION': '1e05',
23:33:33 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
23:33:33 INFO - 'PROGRAMFILES': 'C:\\Program Files',
23:33:33 INFO - 'PROMPT': '$P$G',
23:33:33 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
23:33:33 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
23:33:33 INFO - 'PUBLIC': 'C:\\Users\\Public',
23:33:33 INFO - 'PWD': 'C:\\slave\\test',
23:33:33 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
23:33:33 INFO - 'SLAVEDIR': 'C:\\slave\\',
23:33:33 INFO - 'SYSTEMDRIVE': 'C:',
23:33:33 INFO - 'SYSTEMROOT': 'C:\\windows',
23:33:33 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
23:33:33 INFO - 'TEST1': 'testie',
23:33:33 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
23:33:33 INFO - 'USERDOMAIN': 'T-W732-IX-106',
23:33:33 INFO - 'USERNAME': 'cltbld',
23:33:33 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
23:33:33 INFO - 'WINDIR': 'C:\\windows',
23:33:33 INFO - 'WINDOWS_TRACING_FLAGS': '3',
23:33:33 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
23:33:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
23:33:34 INFO - Ignoring indexes: https://pypi.python.org/simple/
23:33:34 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
23:33:34 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-izfp5l-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
23:33:34 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))
23:33:34 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
23:33:34 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-m1wgez-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
23:33:34 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))
23:33:34 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
23:33:34 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dqwd8b-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
23:33:34 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))
23:33:34 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
23:33:34 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-saey9l-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
23:33:34 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))
23:33:34 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
23:33:34 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-q4chil-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
23:33:35 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))
23:33:35 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
23:33:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-8buwwc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
23:33:35 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))
23:33:35 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
23:33:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-yi1gy7-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
23:33:35 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))
23:33:35 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
23:33:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bjgbgj-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
23:33:35 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))
23:33:35 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
23:33:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-iypkz2-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
23:33:35 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))
23:33:35 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
23:33:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bfkueg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
23:33:35 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))
23:33:35 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
23:33:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-aa5mox-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
23:33:35 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))
23:33:35 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
23:33:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-_nyaue-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
23:33:35 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))
23:33:35 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
23:33:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qpkglj-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
23:33:36 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))
23:33:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
23:33:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-sty1wx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
23:33:36 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))
23:33:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
23:33:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pbkjnr-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
23:33:36 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))
23:33:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
23:33:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-c_ajar-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
23:33:36 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))
23:33:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
23:33:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-9am89n-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
23:33:36 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))
23:33:36 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))
23:33:36 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))
23:33:36 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))
23:33:36 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))
23:33:36 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))
23:33:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10))
23:33: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
23:33: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
23:33:37 INFO - Downloading blessings-1.6.tar.gz
23:33:37 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings
23:33:38 INFO - Installing collected packages: blessings
23:33:38 INFO - Running setup.py install for blessings
23:33:38 INFO - Successfully installed blessings
23:33:38 INFO - Cleaning up...
23:33:41 INFO - Return code: 0
23:33:41 INFO - Done creating virtualenv C:\slave\test\build/venv.
23:33:41 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
23:33:41 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
23:33:41 INFO - Reading from file tmpfile_stdout
23:33:41 INFO - Using _rmtree_windows ...
23:33:41 INFO - Using _rmtree_windows ...
23:33:41 INFO - Current package versions:
23:33:41 INFO - blessings == 1.6
23:33:41 INFO - blobuploader == 1.2.4
23:33:41 INFO - docopt == 0.6.1
23:33:41 INFO - functools32 == 3.2.3-2
23:33:41 INFO - jsonschema == 2.5.1
23:33:41 INFO - manifestparser == 1.1
23:33:41 INFO - mozInstall == 1.12
23:33:41 INFO - mozcrash == 0.17
23:33:41 INFO - mozdebug == 0.1
23:33:41 INFO - mozdevice == 0.48
23:33:41 INFO - mozfile == 1.2
23:33:41 INFO - mozhttpd == 0.7
23:33:41 INFO - mozinfo == 0.9
23:33:41 INFO - mozleak == 0.1
23:33:41 INFO - mozlog == 3.4
23:33:41 INFO - moznetwork == 0.27
23:33:41 INFO - mozprocess == 0.23
23:33:41 INFO - mozprofile == 0.28
23:33:41 INFO - mozrunner == 6.12
23:33:41 INFO - mozscreenshot == 0.1
23:33:41 INFO - mozsystemmonitor == 0.3
23:33:41 INFO - moztest == 0.7
23:33:41 INFO - mozversion == 1.4
23:33:41 INFO - psutil == 3.1.1
23:33:41 INFO - requests == 1.2.3
23:33:41 INFO - Installing None into virtualenv C:\slave\test\build/venv
23:33:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
23:33:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
23:33:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
23:33:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
23:33:41 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 0x018B8640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018EFE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01832B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01909E90>, '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 0x0124A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01975520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-106', 'USERDOMAIN': 'T-W732-IX-106', '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-106', '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
23:33:41 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
23:33:41 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
23:33:41 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
23:33:41 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
23:33:41 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
23:33:41 INFO - 'COMPUTERNAME': 'T-W732-IX-106',
23:33:41 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
23:33:41 INFO - 'CYGWINBASE': 'C:\\cygwin',
23:33:41 INFO - 'DCLOCATION': 'SCL3',
23:33:41 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
23:33:41 INFO - 'FP_NO_HOST_CHECK': 'NO',
23:33:41 INFO - 'HOMEDRIVE': 'C:',
23:33:41 INFO - 'HOMEPATH': '\\Users\\cltbld',
23:33:41 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
23:33:41 INFO - 'KTS_VERSION': '1.19c',
23:33:41 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
23:33:41 INFO - 'LOGONSERVER': '\\\\T-W732-IX-106',
23:33:41 INFO - 'MONDIR': 'C:\\Monitor_config\\',
23:33:41 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
23:33:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
23:33:41 INFO - 'MOZ_NO_REMOTE': '1',
23:33:41 INFO - 'NO_EM_RESTART': '1',
23:33:41 INFO - 'NUMBER_OF_PROCESSORS': '8',
23:33:41 INFO - 'OS': 'Windows_NT',
23:33:41 INFO - 'OURDRIVE': 'C:',
23:33:41 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
23:33:41 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
23:33:41 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
23:33:41 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
23:33:41 INFO - 'PROCESSOR_LEVEL': '6',
23:33:41 INFO - 'PROCESSOR_REVISION': '1e05',
23:33:41 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
23:33:41 INFO - 'PROGRAMFILES': 'C:\\Program Files',
23:33:41 INFO - 'PROMPT': '$P$G',
23:33:41 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
23:33:41 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
23:33:41 INFO - 'PUBLIC': 'C:\\Users\\Public',
23:33:41 INFO - 'PWD': 'C:\\slave\\test',
23:33:41 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
23:33:41 INFO - 'SLAVEDIR': 'C:\\slave\\',
23:33:41 INFO - 'SYSTEMDRIVE': 'C:',
23:33:41 INFO - 'SYSTEMROOT': 'C:\\windows',
23:33:41 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
23:33:41 INFO - 'TEST1': 'testie',
23:33:41 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
23:33:41 INFO - 'USERDOMAIN': 'T-W732-IX-106',
23:33:41 INFO - 'USERNAME': 'cltbld',
23:33:41 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
23:33:41 INFO - 'WINDIR': 'C:\\windows',
23:33:41 INFO - 'WINDOWS_TRACING_FLAGS': '3',
23:33:41 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
23:33:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
23:33:41 INFO - Ignoring indexes: https://pypi.python.org/simple/
23:33:41 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))
23:33:41 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))
23:33:41 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))
23:33:41 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))
23:33:41 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))
23:33:41 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))
23:33:41 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))
23:33:41 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))
23:33:41 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))
23:33:41 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))
23:33:41 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))
23:33:41 INFO - Cleaning up...
23:33:41 INFO - Return code: 0
23:33:41 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv
23:33:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
23:33:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
23:33:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
23:33:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
23:33:41 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018B8640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018EFE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01832B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01909E90>, '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 0x0124A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01975520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-106', 'USERDOMAIN': 'T-W732-IX-106', '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-106', '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
23:33:41 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in C:\slave\test\build
23:33:41 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema
23:33:41 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
23:33:41 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
23:33:41 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
23:33:41 INFO - 'COMPUTERNAME': 'T-W732-IX-106',
23:33:41 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
23:33:41 INFO - 'CYGWINBASE': 'C:\\cygwin',
23:33:41 INFO - 'DCLOCATION': 'SCL3',
23:33:41 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
23:33:41 INFO - 'FP_NO_HOST_CHECK': 'NO',
23:33:41 INFO - 'HOMEDRIVE': 'C:',
23:33:41 INFO - 'HOMEPATH': '\\Users\\cltbld',
23:33:41 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
23:33:41 INFO - 'KTS_VERSION': '1.19c',
23:33:41 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
23:33:41 INFO - 'LOGONSERVER': '\\\\T-W732-IX-106',
23:33:41 INFO - 'MONDIR': 'C:\\Monitor_config\\',
23:33:41 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
23:33:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
23:33:41 INFO - 'MOZ_NO_REMOTE': '1',
23:33:41 INFO - 'NO_EM_RESTART': '1',
23:33:41 INFO - 'NUMBER_OF_PROCESSORS': '8',
23:33:41 INFO - 'OS': 'Windows_NT',
23:33:41 INFO - 'OURDRIVE': 'C:',
23:33:41 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
23:33:41 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
23:33:41 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
23:33:41 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
23:33:41 INFO - 'PROCESSOR_LEVEL': '6',
23:33:41 INFO - 'PROCESSOR_REVISION': '1e05',
23:33:41 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
23:33:41 INFO - 'PROGRAMFILES': 'C:\\Program Files',
23:33:41 INFO - 'PROMPT': '$P$G',
23:33:41 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
23:33:41 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
23:33:41 INFO - 'PUBLIC': 'C:\\Users\\Public',
23:33:41 INFO - 'PWD': 'C:\\slave\\test',
23:33:41 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
23:33:41 INFO - 'SLAVEDIR': 'C:\\slave\\',
23:33:41 INFO - 'SYSTEMDRIVE': 'C:',
23:33:41 INFO - 'SYSTEMROOT': 'C:\\windows',
23:33:41 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
23:33:41 INFO - 'TEST1': 'testie',
23:33:41 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
23:33:41 INFO - 'USERDOMAIN': 'T-W732-IX-106',
23:33:41 INFO - 'USERNAME': 'cltbld',
23:33:41 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
23:33:41 INFO - 'WINDIR': 'C:\\windows',
23:33:41 INFO - 'WINDOWS_TRACING_FLAGS': '3',
23:33:41 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
23:33:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
23:33:42 INFO - Ignoring indexes: https://pypi.python.org/simple/
23:33:42 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages
23:33:42 INFO - Cleaning up...
23:33:42 INFO - Return code: 0
23:33:42 INFO - Running post-action listener: _resource_record_post_action
23:33:42 INFO - Running post-action listener: _start_resource_monitoring
23:33:42 INFO - Starting resource monitoring.
23:33:42 INFO - [mozharness: 2016-10-19 06:33:42.303000Z] Finished create-virtualenv step (success)
23:33:42 INFO - [mozharness: 2016-10-19 06:33:42.303000Z] Running install step.
23:33:42 INFO - Running pre-action listener: _resource_record_pre_action
23:33:42 INFO - Running main action method: install
23:33:42 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
23:33:42 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
23:33:42 INFO - Reading from file tmpfile_stdout
23:33:42 INFO - Using _rmtree_windows ...
23:33:42 INFO - Using _rmtree_windows ...
23:33:42 INFO - Detecting whether we're running mozinstall >=1.0...
23:33:42 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h']
23:33:42 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h
23:33:42 INFO - Reading from file tmpfile_stdout
23:33:42 INFO - Output received:
23:33:42 INFO - Usage: mozinstall-script.py [options] installer
23:33:42 INFO - Options:
23:33:42 INFO - -h, --help show this help message and exit
23:33:42 INFO - -d DEST, --destination=DEST
23:33:42 INFO - Directory to install application into. [default:
23:33:42 INFO - "C:\slave\test"]
23:33:42 INFO - --app=APP Application being installed. [default: firefox]
23:33:42 INFO - Using _rmtree_windows ...
23:33:42 INFO - Using _rmtree_windows ...
23:33:42 INFO - mkdir: C:\slave\test\build\application
23:33:42 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']
23:33:42 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
23:33:45 INFO - Reading from file tmpfile_stdout
23:33:45 INFO - Output received:
23:33:45 INFO - C:\slave\test\build\application\firefox\firefox.exe
23:33:45 INFO - Using _rmtree_windows ...
23:33:45 INFO - Using _rmtree_windows ...
23:33:45 INFO - Running post-action listener: _resource_record_post_action
23:33:45 INFO - [mozharness: 2016-10-19 06:33:45.087000Z] Finished install step (success)
23:33:45 INFO - [mozharness: 2016-10-19 06:33:45.087000Z] Running run-tests step.
23:33:45 INFO - Running pre-action listener: _resource_record_pre_action
23:33:45 INFO - Running main action method: run_tests
23:33:45 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version']
23:33:45 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version
23:33:45 INFO - Python 2.7.3
23:33:45 INFO - Return code: 0
23:33:45 INFO - grabbing minidump binary from tooltool
23:33:45 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')]}
23:33:45 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 0x01909E90>, '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 0x0124A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01975520>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1
23:33:45 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
23:33:45 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
23:33:45 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
23:33:45 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'...
23:33:47 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmptbvysx
23:33:47 INFO - INFO - File integrity verified, renaming tmptbvysx to win32-minidump_stackwalk.exe
23:33:47 INFO - Return code: 0
23:33:47 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755
23:33:47 INFO - mkdir: C:\slave\test\build\blobber_upload_dir
23:33:47 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir
23:33:47 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe
23:33:47 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir
23:33:47 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/1476851407/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-106', '--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
23:33:47 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/1476851407/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-106 --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
23:33:47 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
23:33:47 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
23:33:47 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
23:33:47 INFO - 'COMPUTERNAME': 'T-W732-IX-106',
23:33:47 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
23:33:47 INFO - 'CYGWINBASE': 'C:\\cygwin',
23:33:47 INFO - 'DCLOCATION': 'SCL3',
23:33:47 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
23:33:47 INFO - 'FP_NO_HOST_CHECK': 'NO',
23:33:47 INFO - 'HOMEDRIVE': 'C:',
23:33:47 INFO - 'HOMEPATH': '\\Users\\cltbld',
23:33:47 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
23:33:47 INFO - 'KTS_VERSION': '1.19c',
23:33:47 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
23:33:47 INFO - 'LOGONSERVER': '\\\\T-W732-IX-106',
23:33:47 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir',
23:33:47 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe',
23:33:47 INFO - 'MONDIR': 'C:\\Monitor_config\\',
23:33:47 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
23:33:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
23:33:47 INFO - 'MOZ_NO_REMOTE': '1',
23:33:47 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir',
23:33:47 INFO - 'NO_EM_RESTART': '1',
23:33:47 INFO - 'NUMBER_OF_PROCESSORS': '8',
23:33:47 INFO - 'OS': 'Windows_NT',
23:33:47 INFO - 'OURDRIVE': 'C:',
23:33:47 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;',
23:33:47 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
23:33:47 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
23:33:47 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
23:33:47 INFO - 'PROCESSOR_LEVEL': '6',
23:33:47 INFO - 'PROCESSOR_REVISION': '1e05',
23:33:47 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
23:33:47 INFO - 'PROGRAMFILES': 'C:\\Program Files',
23:33:47 INFO - 'PROMPT': '$P$G',
23:33:47 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
23:33:47 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
23:33:47 INFO - 'PUBLIC': 'C:\\Users\\Public',
23:33:47 INFO - 'PWD': 'C:\\slave\\test',
23:33:47 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos',
23:33:47 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
23:33:47 INFO - 'SLAVEDIR': 'C:\\slave\\',
23:33:47 INFO - 'SYSTEMDRIVE': 'C:',
23:33:47 INFO - 'SYSTEMROOT': 'C:\\windows',
23:33:47 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
23:33:47 INFO - 'TEST1': 'testie',
23:33:47 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
23:33:47 INFO - 'USERDOMAIN': 'T-W732-IX-106',
23:33:47 INFO - 'USERNAME': 'cltbld',
23:33:47 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
23:33:47 INFO - 'WINDIR': 'C:\\windows',
23:33:47 INFO - 'WINDOWS_TRACING_FLAGS': '3',
23:33:47 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
23:33:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
23:33:47 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/1476851407/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-106', '--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
23:33:48 INFO - mozversion application_buildid: 20161018213007
23:33:48 INFO - mozversion application_changeset: f52b380bc02e1022631ab8dcd43ed246930974c3
23:33:48 INFO - mozversion application_display_name: Nightly
23:33:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
23:33:48 INFO - mozversion application_name: Firefox
23:33:48 INFO - mozversion application_remotingname: firefox
23:33:48 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team
23:33:48 INFO - mozversion application_vendor: Mozilla
23:33:48 INFO - mozversion application_version: 52.0a1
23:33:48 INFO - mozversion platform_buildid: 20161018213007
23:33:48 INFO - mozversion platform_changeset: f52b380bc02e1022631ab8dcd43ed246930974c3
23:33:48 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team
23:33:48 INFO - mozversion platform_version: 52.0a1
23:33:48 INFO - using testdate: 1476858828
23:33:48 INFO - actual date: 1476858828
23:33:48 INFO - starting webserver on 'localhost:49621'
23:33:48 INFO - SUITE-START | Running 2 tests
23:33:48 INFO - TEST-START | dromaeo_css
23:33:48 INFO - operating with platform_type : w7_
23:33:48 INFO - Initialising browser for dromaeo_css test...
23:33:48 INFO - TEST-INFO | started process 628 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpwznlxb\profile http://localhost:49621/getInfo.html)
23:33:56 INFO - PROCESS | 628 | __metrics Screen width/height:1600/1200
23:33:56 INFO - PROCESS | 628 | colorDepth:24
23:33:56 INFO - PROCESS | 628 | Browser inner width/height: 1010/674
23:33:56 INFO - PROCESS | 628 | __metrics
23:33:56 INFO - PROCESS | 628 | 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"
23:33:56 INFO - PROCESS | 628 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
23:33:58 INFO - TEST-INFO | 628: exit 0
23:33:58 INFO - Browser initialized.
23:33:58 INFO - Running cycle 1/1 for dromaeo_css test...
23:33:58 INFO - TEST-INFO | started process 1100 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpwznlxb\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1)
23:33:58 INFO - PROCESS | 1100 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0
23:34:09 INFO - PROCESS | 1100 | RSS: Main: 123027456
23:34:09 INFO - PROCESS | 1100 |
23:35:00 INFO - PROCESS | 1100 | 1476858900789 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]
23:37:12 INFO - PROCESS | 1100 | Cycle 1(1): loaded http://localhost:49621/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49621/tests/dromaeo/cssquery-ext.html)
23:37:14 INFO - PROCESS | 1100 | RSS: Main: 262053888
23:37:14 INFO - PROCESS | 1100 |
23:40:17 INFO - PROCESS | 1100 | Cycle 1(1): loaded http://localhost:49621/tests/dromaeo/cssquery-ext.html (next: http://localhost:49621/tests/dromaeo/cssquery-jquery.html)
23:40:17 INFO - PROCESS | 1100 | RSS: Main: 195481600
23:40:17 INFO - PROCESS | 1100 |
23:43:20 INFO - PROCESS | 1100 | Cycle 1(1): loaded http://localhost:49621/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49621/tests/dromaeo/cssquery-mootools.html)
23:43:20 INFO - PROCESS | 1100 | RSS: Main: 147582976
23:43:20 INFO - PROCESS | 1100 |
23:46:22 INFO - PROCESS | 1100 | Cycle 1(1): loaded http://localhost:49621/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49621/tests/dromaeo/cssquery-prototype.html)
23:46:23 INFO - PROCESS | 1100 | RSS: Main: 142831616
23:46:23 INFO - PROCESS | 1100 |
23:49:26 INFO - PROCESS | 1100 | Cycle 1(1): loaded http://localhost:49621/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49621/tests/dromaeo/cssquery-yui.html)
23:49:26 INFO - PROCESS | 1100 | RSS: Main: 130592768
23:49:26 INFO - PROCESS | 1100 |
23:52:29 INFO - PROCESS | 1100 | Cycle 1(1): loaded http://localhost:49621/tests/dromaeo/cssquery-yui.html (next: http://localhost:49621/tests/dromaeo/cssquery-dojo.html)
23:52:29 INFO - PROCESS | 1100 | RSS: Main: 232235008
23:52:29 INFO - PROCESS | 1100 |
23:52:29 INFO - PROCESS | 1100 | __start_tp_report
23:52:29 INFO - PROCESS | 1100 | _x_x_mozilla_page_load
23:52:29 INFO - PROCESS | 1100 | _x_x_mozilla_page_load_details
23:52:29 INFO - PROCESS | 1100 | |i|pagename|runs|
23:52:29 INFO - PROCESS | 1100 | |0;dojo.html;2548.2610043087507;2556.8977240910363;2571.8071144664154;2576.6650335456393;2622.3575224070105;4399.472063352398;4441.222786012443;4497.853047472894;4500.909981800362;4516.706414083083;4359.542248063955;4509.932351014738;4530.932036019454;4564.002417353002;4569.657275704327;3917.334053210947;3927.52869655641;3986.5016872890887;4121.732087414309;4142.295809712341;3901.7904449620564;3942.704297177723;3949.6050394960416;3984.422258772476;3985.4619626350363;3984.3027470192746;3985.083430810915;4013.337799263118;4023.4166045923325;4056.5943405659345;3850.9229815403814;3943.822527986233;3988.102676897704;4060.4112403701592;4086.9373962769896;3940.133170702441;3980.7810570418615;3990.960090399117;4013.0769922917602;4068.6338229559187;4328.48058233014;4393.319035549495;4404.845830395952;4450.443694538183;4577.816887324503;4547.749873756942;4609.815607375701;4614.2617181250835;4719.740414277213;4776.259679749644;4658.34783130362;4702.905941881178;4707.105649926503;4759.333693282943;4765.523447655207;4312.61186493214;4314.870553883388;4377.905523619095;4410.933835992462;4530.637548996073;4693.413323334583;4823.662343635911;4827.372441582642;4835.758212089381;4936.136176169156;4498.302763071729;4543.591076803037;4551.817927282872;4679.157751604744;4726.432828060655;4596.954030459719;4630.6758526902795;4650.348951146842;4679.344891715163;4739.66822322441;4620.19146649335;4622.907541849144;4625.074985002945;4633.513481084437;4681.578657920803;4636.420447444069;4638.840289927518;4641.118187544355;4647.140279048387;4647.9767601161775;3138.466460701686;3144.559761633373;3147.7953932994283;3207.7914935529116;3289.753268504872;2867.426514697069;2883.726046025666;2885.9422811544073;2893.508103622389;2926.7219614136625;4353.542877997828;4431.778411079433;4466.6203372713035;4476.485204201494;4545.65907556928;4604.700694454917;4658.114958157939;4708.599769019604;4720.749001514601;4739.265413860857;4319.200947824663;4361.80371883266;4399.5160532341415;4415.602595766396;4524.253498172765;3897.824597893102;3928.783916884599;3956.604339566078;3977.900552486153;4157.563455837094;4860.416749990094;4924.138275801792;4997.600191984559;5011.173156429149;5033.345665063518;4599.908001839877;4613.261878099488;4683.789229484613;4757.666963312673;4870.464248932543;4901.264810278487;4919.606431485544;4955.331030311006;4971.6022718183185;4989.950100498949;4581.885452863572;4679.649026322972;4680.806394369414;4696.8590942271785;4754.025424787841;4701.882452938704;4712.104700106968;4803.735794531334;4804.95195048045;4837.274408838703;4528.88677783058;4542.545745425563;4587.082583483383;4679.321498382784;4684.344191813217;4497.370368148397;4568.79440425175;4572.8856778579475;4572.88567785808;4741.810327586996;4482.596566309048;4497.437820272466;4505.873531617096;4532.025614492768;4708.952910470852;4582.9541704582525;4583.129205450954;4585.495595484427;4586.151561961048;4593.701409408378;4530.750808705553;4531.796069176961;4538.341940418687;4617.376654151781;4667
23:52:29 INFO - PROCESS | 1100 | |1;ext.html;492.4386199732305;530.8354410132861;549.1762356465302;561.6349372907612;564.0326839470307;37028.44457333139;37791.05522361939;37867.24265514688;39492.21015579686;39608;37500.624993750054;37868;37980.43029354562;37989.43015854764;38552.614473855254;6216;7495.287947644965;7601.391888648895;7672.65473053714;7734.1492435832015;6274.215723034621;9262.259019278441;9459.668911588096;9471.384360016611;9781.168600668952;9409.388389754678;9409.62361505539;9453.196478299318;9570;9646.324757266993;6491.350864913471;6571.408573228385;9342.346035777498;9388.953055234748;9822.263330250224;9292.256619470427;9337.393069450463;9339.719808405758;9501.382410143318;9996.500174991294;34781.13047173816;35107.824460877535;35455.75904843318;35611.82194089039;35746.8212658935;2647.2587675450904;2771.3071732066983;2913.941721165565;2989.8355590442516;3394.4738565522384;27177.728222717717;27526;27768.861155694296;28375.858120709265;28425.431491370262;3225.5000474926424;3235.2558911450496;3290.588676415448;3360.6975372216375;3452.7410444216785;324686;427826;430161;432562;433307;48320.7583962085;48377.758111209216;48595;48797.26804097941;48884.044638214786;49838.750806245735;50022.74988625106;50264.49735502671;51396.74301628543;52251.73874130605;11363.977242048255;11648.94175529117;11679.591214307458;12682.80975785364;12891.226526408445;8849.4247873888;9101.908980910106;9233.984261731204;9866.45734484596;10426.947865260625;15455.38178472871;15914.885957983062;15946.890356380394;16157.838421615634;16504;6254.499640028786;6356.728654269072;6383.467967687814;6383.744650214032;6410.8717825643225;714.7069701422391;823.9505629662119;851.5146366571119;889.8754174415587;898.0794685447469;24710.752892471206;25728.228153155436;25851.224463265757;25955.72340795617;26548.469030619664;863.8099618084011;893.1202765276198;906.5467266366817;923.3905622289257;958.930792166729;13370.933145334211;13596.456141754417;13638.249896255602;13675.452981880811;14027.579172624837;187966;494687;495571;499328;528711;45630.54369456263;45920.54079459229;46066.078678426915;46089.30866037012;46590;951.923846092311;972.5672075926286;1035.1977217656377;1058.692979036071;1063.1813503602339;25183.49633007293;26311.868440657676;26974.595381069692;27195.55240028407;27575.586366204923;37404;38342.616573833904;38517;38957.80521097376;39691.40462893001;345626;345642;348986;349914;558711;34333;34572.82713586416;34774.47838282478;35158.47262291119;35618.821905890305;38530;38630.6136938627;39094;39338;40003.79998099991;33756.96187343151;34154.48768268528;34790;34996;35512.64487355093;34027;34602;34763.826180868935;34907.30185396328;35861.28277434385;32379.19052023718;32808;32944.1763955903;33328.50007249942;33686.66313336933;29224.707752922197;30248.243793905327;31296.21759456032;31326.216844579067;31619.84190079035;21109.68335475;21913.342599722033;22503.212387566356;22522;23745.762542374356
23:52:29 INFO - PROCESS | 1100 | |2;jquery.html;268.68161228943717;287.2187649593106;291.0076638662162;296.4515646054799;305.1638510481282;11957.22278051926;12751.661075587046;12794.360281985899;13020.869791302084;15804;15723.13522756248;15854.365825366971;16169.272382742776;16217;16582.83417165828;4805.831795887145;5013.949860501385;5166.405863325709;5249.118748348436;5292.629515933886;4856.198727210006;4948.97525512372;4960.97519512402;4973.825916092937;4987.700737955716;3640.2537479816574;3832.1952389998132;4911.729854857981;5500.532454741332;5694.943050569504;5707.971460142693;5709.543236541067;5759.625624334405;5768.134779783024;5818.389069147764;4804.903901921977;4892.6575139740235;6211.9068213976825;6586.176727909012;6667.866462701352;22001.449963751034;23267.18564850239;23710.7628923712;24392.146274880295;24472;2062.711220429141;2084.6351888419617;2103.0956688623887;2136.7115439415793;2209.734831820192;13765.724685506233;14824.925875370554;14950.62623434412;15004.174770387624;15255.313510892036;1798.856091512676;1898.8480921526245;2020.9898950505153;2030.6547886859269;2039.225094464109;87686.56156719239;87990.5600472;89221;90100;91078;15665.29506172225;15994.520164394855;16112.75830862538;16279.51161465158;16304.51086467408;22447.77552224457;26987.460250794873;27148;27536.724632753416;27572.034978775646;7774.533527988338;8150.4294699370475;8388.958055209807;8527.40308178434;8658.826823463496;7113.146422429341;7138.535995160298;7156.141263048364;7199.604021778748;7265.818354541073;7774.766756997299;7913.881291780628;7930.762077137695;7950.681972721142;8012.759617211493;3024.9697503025127;3094.5667606535103;3172.302093539464;3178.586783718148;3179.7933134346194;702.5047341624143;731.9670614822346;770.3067239484396;846.1411667157839;851.9105493923173;16547.83452165487;16793;16830.316968303316;17076.914615426842;17355;744.3301029073768;752.8117970507374;766.5439063757055;777.4169372970272;792.0811858244409;9101.499417531966;9336.253099752004;9566.760830979141;9623.855642165374;9799.338786843633;364944;366276;366366;374006;392728;87174;87323.56338218268;87967.56016219877;88141.55929220314;93661.5316923411;771.097815555801;803.2890891561011;806.7458750493576;809.1059379385694;828.4697793412221;14195.148291102154;15343.925925185129;15577.299021543831;15580.376784928476;15892.205389730236;38880.2223955525;39062.21875562416;39231.019224519616;39896.80309590717;40783.980400490225;50633.49366506435;53279.409706253246;55345;56589.73641054312;56645.88447635419;6637.672465507015;6666.733330666913;6740.9103178014975;6741.191057072986;6833.145856767904;8501.87247191305;11000.394978276272;11401.491671832184;11440.42797860087;12286.631401057983;9668.274879383933;9789.167920726817;10073.949630252095;10082.243831712503;10097.293189476666;9504.287178461505;9578.329516933747;9587.808243835223;9698.321117521706;9846.359986600848;9614;9760.267979901679;9919.553620087258;9950.950245249018;10129.898701012895;5646.689432081275;6089.939100608937;6637.402633762791;6902.896456553256;6923.89614155778;8807.691730789391;9706.6117355305;9954.203663706741;9979.550920208463;9980.850287245843
23:52:29 INFO - PROCESS | 1100 | |3;mootools.html;1019.383273119763;1104.5747387255904;1112.460456678511;1216.2945491614864;1310.4103153580886;5641.266635337405;6241.906371404432;6406.199225096863;6466.515011374142;6617.7757114933775;6479.967600162005;6536.934630653692;6599.5380323377385;6665.13353264075;6674.96662516688;1700.4218565687663;1729.8270172982727;1737.5656085978505;1737.8696597755156;1783.777027871516;1716.4164184177378;1726.9222884970206;1737.4874412048414;1744.1366523570787;1757.2795153986867;1706.053140507018;1709.1710520397596;1711.9728163102102;1732.315735284562;1742.3814545836235;1628.9755653665204;1634.0195882470482;1634.207409406437;1646.7282898321762;1652.3308060235504;1617.6683779825107;1632.8938618989728;1636.0265641943024;1636.9508914732578;1638.2382192280527;6171.814845554641;6253.53098517613;6267;6412.7114279857515;6513.250976137758;3730.757500762442;3799.8290076946605;3818.3699689551468;3841.481400010991;3903.6291552302487;2449.3631655769627;2481.6401621764744;2515.9936025589727;2557.105013245368;2575.1115865026536;764;799.7720649614832;801.5110782422718;803.8432505661399;819.1358117186365;8282.917170828276;8309.916900830975;8430.578471076422;8496.192861678132;8595.527246001468;3068.5550595163577;3113.7508999280003;3128.6089238845143;3133.27934575049;3137.6862313768443;6493.805185844432;6547.5416720830035;6547.9017814732815;6671.799846004627;6762.492813039042;3658.3780757270797;3662.6886714629554;3663.377225871555;3676.915309983508;3693.021349342426;3097.9776673697625;3151.896836107344;3492.5983511896457;3503.913786726123;3545.645435456434;2685.516607010718;2711.1053352393665;2724.1146627346193;2748.6839013513304;2778.569321755131;777.2150128370274;794.4796158516181;800.6837299266873;808.244291587356;808.9231523005305;656.8095252376853;747.1445195251515;758.9165191828895;780.6994307191773;798.1180795221197;4412.602865742098;4480.4175457191;4482.439695038121;4518.322251662211;4632.328312394751;712.4051417066704;750.8348163404042;752.8983587215656;757.1406453675008;762.3634265388463;3210.678932106771;3223.9194020149685;3250.73994080473;3257.7556683248376;3282.5896762904636;7589.734359297398;7761.9611901940125;7769.6115194240065;7800.6099695016155;7874.881876771853;7783.961080194563;7825.726099586487;7838.333741631911;7855;7891.053073631087;6038.63768173911;6095.542834287447;6230.968845155745;6256.968715156395;6328.778492752824;4515.887102822456;4528.524504926935;4552.499225085172;4555.908881822411;4589.7705114744795;7249.311315425026;7315.195328513752;7370.336669699753;7381.8154546136775;7415.740449084256;7150.10623672041;7385.889211661937;7399.186089530255;7405.851882962418;7582.734604288823;5621.072523033654;5676.5742569308295;5702.40124786908;5749.8275051748515;5762.481376676119;5611;5822.573469499999;6023.668698221464;6210.968945155245;6424.518161138027;5638.971805140948;5657.3776884543495;5695.401982791747;5704.80033198836;5714.05718056516;5662.943370566241;5670.206171136127;5692.715364231854;5737.942620573741;5810.68041257718;5059.3422855030085;5330.520253177233;5333.573314134782;5359.115745901882;5394.136938089887;4014.538328092307;4016.8393264269102;4030.6170913763144;4089.5910408958866;4159.916801664011;4007;4037.0916543777416;4069.491313585802;4071.1450595374117;4189.308764053897
23:52:29 INFO - PROCESS | 1100 | |4;prototype.html;232.09367420224032;235.55480142530615;245.56167241473972;246.42582782117668;250.81439734596398;4864.318995340651;4865.756712164395;4887.193613053842;4914.262860570916;4935.161022626153;4575.8169673213115;4682.6019788318035;4683.50823163568;4694.535368931312;4906.460289368167;3446.190145315849;3463.930721385571;3501.457274122509;3510.2452972610886;3522.049046757374;3512.859485620585;3515.6835884770367;3530.947035794465;3543.379908516012;3597.712183025352;3481.495183198435;3482.7562070655063;3493.2140268439525;3529.2235708144167;3574.749767516275;3336.632970373257;3442.707369873564;3482.6169121396624;3493.4410494320914;3506.63180366063;3012.0963710886645;3032.4996375598;3034.620672415948;3105.6117985251844;3176.3964846679055;4639.6752227344095;4645.744484053375;4690.202665546865;4778.522147785194;4857.8299759508245;4059.2084543514025;4059.3505039193883;4059.7361171523758;4063.187362527506;4232.25935461293;4728.787204575802;4948;4980.377452818398;4985.35190425246;5225.242339860737;3231.2406584452633;3236.4336241157703;3247.8213698246586;3249.5775549178693;3253.2029652734996;5215.765290561933;5223.529882310572;5233.781224546876;5250.319897632721;5282.683039017671;3279.6720327967014;3287.917802054968;3289.9472168905722;3318.253392986607;3341.081202669285;3175.618925728881;3338.9499157512655;3339.6159441664036;3341.0979035660234;3362.2098806780386;3827.3302172119766;3954.8418063277722;3962.32640451124;3973.205358928226;4077.388391741203;4999.400071991312;5001.374828146481;5007.89826238227;5014.946861159197;5159.845204643867;1152.7060599547062;1162.6628277799343;1162.8895254950767;1164.947577359021;1168.4566676495301;700.8948657701386;719.1837264704598;726.556800351785;729.3326606155308;731.6159016516265;4457.821687132543;4468.961106447582;4559.749213793273;4714.504976977437;4854.320395144682;5127.282180494733;5205.609579281493;5221.399539053057;5229.529342359206;5281.788728450819;4901.460839307673;4901.975490122527;4910.950890491121;4925.482824303468;5062.670926389773;2743.5198840202884;2761.2130542795608;2768.5708715149185;2782.1792571191545;2910.7089291070724;5360.731963401892;5517.972410137923;5522.779088836482;5526.4197259288;5692.117721753135;5199.584033277329;5277.788888444419;5310.6813591184655;5363.892722145535;5382.354117505846;5511.374144627424;5565.745872921473;5566.693831839207;5572.888542229214;5734.455226753452;5250.579953603779;5253.290805741176;5259.55293800016;5348.732563371894;5459.208414779755;5105.366282789471;5110.872228194325;5113.977204559177;5148.2020286855595;5148.922766158586;5523.28197334344;5551.583631227593;5645.604807663589;5645.943540564542;5772.307323121085;3240.9351812963137;3258.6904244096772;3265.9836700817295;3314.502824576333;3375.8480868360493;5087.516685914832;5104.464031276811;5249.212618107162;5273.393559740679;5361.329833770778;5073.213651883964;5093.7707803148205;5115.590752739698;5169.405518365436;5294.49702278283;5079.949200507947;5087.618428617794;5115.437301896863;5235.65968212065;5255.710935898563;4978.253262010582;5070.442251352274;5096.184610462308;5211.348581427322;5265.6050796189675;5058.608882557179;5085.288059671575;5089.491050894881;5096.51583099605;5108.59131269505;4772.379590653193;4801.279808028824;5016.197408414636;5106.540411362995;5152.948470515247
23:52:29 INFO - PROCESS | 1100 | |5;yui.html;575.2607898849978;593.5014587746292;600.8497875531117;609.0133982947624;641.249737806766;13057.15128516647;13443.663908402283;13458.39437225326;13558.389872455738;13652.317384130803;12192.268463892173;12336.62990110298;12464.750704985894;12552.497900084009;12923.09538332317;568.7611203294626;574.6092656993243;575.2090875046811;585.0989476206637;585.6222736335061;479.26911460023393;566.9177969194465;568.5196009372095;569.0610492687078;577.1054864959317;497.3807609526145;600.8197540737783;607.9118527813464;614.4930432393271;631.1290419221491;596.7523477756747;597.7250464786204;600.3216365506971;603.957722959393;613.3621034124506;361.3531778117167;434.20540411047705;435.8757754040083;437.8467536362248;448.9753063581502;10098.596056157818;12061.517539298418;12348.567800126952;12825.294608796512;13258.86741132586;12955.264009559536;13327;13406.664833379145;13470.39383227757;13788;10785.892141078568;11170.27393219438;11411.600593979254;11429.257098288585;11587.942060289644;3359.3617212729746;3398.9490157647656;3480.7041401480906;3544.6987006104514;3558.893233203008;80997;81728;82054.17945820525;83486.3890615289;84559;10081.395116293046;10239.385636861813;10294.3823370598;10405.47972601367;10607.204459665401;18282.63434731298;18664.53338666517;18870.52823679419;18943.33698320552;19836.20655173777;6866.279040700654;7059.5058345915295;7066.752663656747;7115.71537138519;7148.606826624482;3441.208522039945;3619.4570814377525;3620.601733809279;3737.7757334560015;3787.715921305858;2738.931526711848;2759;2782.1792571191545;2794.050022992192;2816.816906901045;515.8297761738618;518.9014087323396;521.2415934814835;522.6028218553927;525.660948688094;507.91873300272516;558.6983029164287;575.847400438884;643.2698886763557;658.4567731621431;8147.674093036212;8187.304079153219;8221.465604735673;8748;8837.381383303107;562.9662220266797;574.2678085441096;672.7275453441364;673.9015404889998;695.7356204642203;4259.914801704011;4322.956770432318;4397.142557201315;4401.559844015637;4429.15845989261;111459;114736;122493.38753306176;123463;124599;83482.58258708789;92125.53937230271;96464.58838528952;100061.49969250253;100658.49670751746;87864;92175.07824921665;92330.84360720315;93449;93606.53196734108;8409.83180336402;8680.479171249744;8861.505544631904;8969.686060988095;9155.313351498531;47598.76200619113;47961.5203847957;48214.21429428491;50317;50433.20018197808;68371.31628683848;69636.30363696298;70037;71023.28976710167;71698.64150679213;12139.210951288138;12292.134384995885;12478.313692746986;12492.125551211328;12528.185667931555;13677;13699.246541440316;13991.020628555903;14291.499797507038;14568.635784105058;12929.288889111189;12950.158239714388;13096.934515327363;13274.469021239041;13377.528471868281;12788.444249095988;12942.935285323512;13153.736925261632;13364.398602063126;13759.93600959864;12698.30159341245;12942.223466592035;13271.668208294484;13285.40215690277;13671.658208544468;11010.50452729613;11038.815122567783;11050.33697978124;11519.539218431504;11862.644120676394;9673.806523869624;10304.896951030394;10314.896851031393;10594.629187978384;10980.450977451255
23:52:29 INFO - PROCESS | 1100 | __end_tp_report
23:52:29 INFO - PROCESS | 1100 | __start_cc_report
23:52:29 INFO - PROCESS | 1100 | _x_x_mozilla_cycle_collect,2163
23:52:29 INFO - PROCESS | 1100 | __end_cc_report
23:52:29 INFO - PROCESS | 1100 | __startTimestamp1476859949696__endTimestamp
23:52:29 INFO - PROCESS | 1100 |
23:52:29 INFO - PROCESS | 1100 | ------- Summary: start -------
23:52:29 INFO - PROCESS | 1100 | Number of tests: 6
23:52:29 INFO - PROCESS | 1100 |
23:52:29 INFO - PROCESS | 1100 | [#0] dojo.html Cycles:165 Average:4353.18 Median:4543.59 stddev:549.49 (12.1%) stddev-sans-first:532.62
23:52:29 INFO - PROCESS | 1100 | Values: 2548.3 2556.9 2571.8 2576.7 2622.4 4399.5 4441.2 4497.9 4500.9 4516.7 4359.5 4509.9 4530.9 4564.0 4569.7 3917.3 3927.5 3986.5 4121.7 4142.3 3901.8 3942.7 3949.6 3984.4 3985.5 3984.3 3985.1 4013.3 4023.4 4056.6 3850.9 3943.8 3988.1 4060.4 4086.9 3940.1 3980.8 3991.0 4013.1 4068.6 4328.5 4393.3 4404.8 4450.4 4577.8 4547.7 4609.8 4614.3 4719.7 4776.3 4658.3 4702.9 4707.1 4759.3 4765.5 4312.6 4314.9 4377.9 4410.9 4530.6 4693.4 4823.7 4827.4 4835.8 4936.1 4498.3 4543.6 4551.8 4679.2 4726.4 4597.0 4630.7 4650.3 4679.3 4739.7 4620.2 4622.9 4625.1 4633.5 4681.6 4636.4 4638.8 4641.1 4647.1 4648.0 3138.5 3144.6 3147.8 3207.8 3289.8 2867.4 2883.7 2885.9 2893.5 2926.7 4353.5 4431.8 4466.6 4476.5 4545.7 4604.7 4658.1 4708.6 4720.7 4739.3 4319.2 4361.8 4399.5 4415.6 4524.3 3897.8 3928.8 3956.6 3977.9 4157.6 4860.4 4924.1 4997.6 5011.2 5033.3 4599.9 4613.3 4683.8 4757.7 4870.5 4901.3 4919.6 4955.3 4971.6 4990.0 4581.9 4679.6 4680.8 4696.9 4754.0 4701.9 4712.1 4803.7 4805.0 4837.3 4528.9 4542.5 4587.1 4679.3 4684.3 4497.4 4568.8 4572.9 4572.9 4741.8 4482.6 4497.4 4505.9 4532.0 4709.0 4583.0 4583.1 4585.5 4586.2 4593.7 4530.8 4531.8 4538.3 4617.4 4667.0
23:52:29 INFO - PROCESS | 1100 |
23:52:29 INFO - PROCESS | 1100 | [#1] ext.html Cycles:180 Average:54358.68 Median:37924.22 stddev:112977.93 (297.9%) stddev-sans-first:113222.48
23:52:29 INFO - PROCESS | 1100 | Values: 492.4 530.8 549.2 561.6 564.0 37028.4 37791.1 37867.2 39492.2 39608.0 37500.6 37868.0 37980.4 37989.4 38552.6 6216.0 7495.3 7601.4 7672.7 7734.1 6274.2 9262.3 9459.7 9471.4 9781.2 9409.4 9409.6 9453.2 9570.0 9646.3 6491.4 6571.4 9342.3 9389.0 9822.3 9292.3 9337.4 9339.7 9501.4 9996.5 34781.1 35107.8 35455.8 35611.8 35746.8 2647.3 2771.3 2913.9 2989.8 3394.5 27177.7 27526.0 27768.9 28375.9 28425.4 3225.5 3235.3 3290.6 3360.7 3452.7 324686.0 427826.0 430161.0 432562.0 433307.0 48320.8 48377.8 48595.0 48797.3 48884.0 49838.8 50022.7 50264.5 51396.7 52251.7 11364.0 11648.9 11679.6 12682.8 12891.2 8849.4 9101.9 9234.0 9866.5 10426.9 15455.4 15914.9 15946.9 16157.8 16504.0 6254.5 6356.7 6383.5 6383.7 6410.9 714.7 824.0 851.5 889.9 898.1 24710.8 25728.2 25851.2 25955.7 26548.5 863.8 893.1 906.5 923.4 958.9 13370.9 13596.5 13638.2 13675.5 14027.6 187966.0 494687.0 495571.0 499328.0 528711.0 45630.5 45920.5 46066.1 46089.3 46590.0 951.9 972.6 1035.2 1058.7 1063.2 25183.5 26311.9 26974.6 27195.6 27575.6 37404.0 38342.6 38517.0 38957.8 39691.4 345626.0 345642.0 348986.0 349914.0 558711.0 34333.0 34572.8 34774.5 35158.5 35618.8 38530.0 38630.6 39094.0 39338.0 40003.8 33757.0 34154.5 34790.0 34996.0 35512.6 34027.0 34602.0 34763.8 34907.3 35861.3 32379.2 32808.0 32944.2 33328.5 33686.7 29224.7 30248.2 31296.2 31326.2 31619.8 21109.7 21913.3 22503.2 22522.0 23745.8
23:52:29 INFO - PROCESS | 1100 |
23:52:29 INFO - PROCESS | 1100 | [#2] jquery.html Cycles:180 Average:25546.32 Median:27899.95 stddev:62481.45 (223.9%) stddev-sans-first:62627.90
23:52:29 INFO - PROCESS | 1100 | Values: 268.7 287.2 291.0 296.5 305.2 11957.2 12751.7 12794.4 13020.9 15804.0 15723.1 15854.4 16169.3 16217.0 16582.8 4805.8 5013.9 5166.4 5249.1 5292.6 4856.2 4949.0 4961.0 4973.8 4987.7 3640.3 3832.2 4911.7 5500.5 5694.9 5708.0 5709.5 5759.6 5768.1 5818.4 4804.9 4892.7 6211.9 6586.2 6667.9 22001.4 23267.2 23710.8 24392.1 24472.0 2062.7 2084.6 2103.1 2136.7 2209.7 13765.7 14824.9 14950.6 15004.2 15255.3 1798.9 1898.8 2021.0 2030.7 2039.2 87686.6 87990.6 89221.0 90100.0 91078.0 15665.3 15994.5 16112.8 16279.5 16304.5 22447.8 26987.5 27148.0 27536.7 27572.0 7774.5 8150.4 8389.0 8527.4 8658.8 7113.1 7138.5 7156.1 7199.6 7265.8 7774.8 7913.9 7930.8 7950.7 8012.8 3025.0 3094.6 3172.3 3178.6 3179.8 702.5 732.0 770.3 846.1 851.9 16547.8 16793.0 16830.3 17076.9 17355.0 744.3 752.8 766.5 777.4 792.1 9101.5 9336.3 9566.8 9623.9 9799.3 364944.0 366276.0 366366.0 374006.0 392728.0 87174.0 87323.6 87967.6 88141.6 93661.5 771.1 803.3 806.7 809.1 828.5 14195.1 15343.9 15577.3 15580.4 15892.2 38880.2 39062.2 39231.0 39896.8 40784.0 50633.5 53279.4 55345.0 56589.7 56645.9 6637.7 6666.7 6740.9 6741.2 6833.1 8501.9 11000.4 11401.5 11440.4 12286.6 9668.3 9789.2 10073.9 10082.2 10097.3 9504.3 9578.3 9587.8 9698.3 9846.4 9614.0 9760.3 9919.6 9951.0 10129.9 5646.7 6089.9 6637.4 6902.9 6923.9 8807.7 9706.6 9954.2 9979.6 9980.9
23:52:29 INFO - PROCESS | 1100 |
23:52:29 INFO - PROCESS | 1100 | [#3] mootools.html Cycles:180 Average:4208.51 Median:5616.04 stddev:2339.03 (41.6%) stddev-sans-first:2333.31
23:52:29 INFO - PROCESS | 1100 | Values: 1019.4 1104.6 1112.5 1216.3 1310.4 5641.3 6241.9 6406.2 6466.5 6617.8 6480.0 6536.9 6599.5 6665.1 6675.0 1700.4 1729.8 1737.6 1737.9 1783.8 1716.4 1726.9 1737.5 1744.1 1757.3 1706.1 1709.2 1712.0 1732.3 1742.4 1629.0 1634.0 1634.2 1646.7 1652.3 1617.7 1632.9 1636.0 1637.0 1638.2 6171.8 6253.5 6267.0 6412.7 6513.3 3730.8 3799.8 3818.4 3841.5 3903.6 2449.4 2481.6 2516.0 2557.1 2575.1 764.0 799.8 801.5 803.8 819.1 8282.9 8309.9 8430.6 8496.2 8595.5 3068.6 3113.8 3128.6 3133.3 3137.7 6493.8 6547.5 6547.9 6671.8 6762.5 3658.4 3662.7 3663.4 3676.9 3693.0 3098.0 3151.9 3492.6 3503.9 3545.6 2685.5 2711.1 2724.1 2748.7 2778.6 777.2 794.5 800.7 808.2 808.9 656.8 747.1 758.9 780.7 798.1 4412.6 4480.4 4482.4 4518.3 4632.3 712.4 750.8 752.9 757.1 762.4 3210.7 3223.9 3250.7 3257.8 3282.6 7589.7 7762.0 7769.6 7800.6 7874.9 7784.0 7825.7 7838.3 7855.0 7891.1 6038.6 6095.5 6231.0 6257.0 6328.8 4515.9 4528.5 4552.5 4555.9 4589.8 7249.3 7315.2 7370.3 7381.8 7415.7 7150.1 7385.9 7399.2 7405.9 7582.7 5621.1 5676.6 5702.4 5749.8 5762.5 5611.0 5822.6 6023.7 6211.0 6424.5 5639.0 5657.4 5695.4 5704.8 5714.1 5662.9 5670.2 5692.7 5737.9 5810.7 5059.3 5330.5 5333.6 5359.1 5394.1 4014.5 4016.8 4030.6 4089.6 4159.9 4007.0 4037.1 4069.5 4071.1 4189.3
23:52:29 INFO - PROCESS | 1100 |
23:52:29 INFO - PROCESS | 1100 | [#4] prototype.html Cycles:180 Average:4186.63 Median:4901.72 stddev:1348.31 (27.5%) stddev-sans-first:1319.02
23:52:29 INFO - PROCESS | 1100 | Values: 232.1 235.6 245.6 246.4 250.8 4864.3 4865.8 4887.2 4914.3 4935.2 4575.8 4682.6 4683.5 4694.5 4906.5 3446.2 3463.9 3501.5 3510.2 3522.0 3512.9 3515.7 3530.9 3543.4 3597.7 3481.5 3482.8 3493.2 3529.2 3574.7 3336.6 3442.7 3482.6 3493.4 3506.6 3012.1 3032.5 3034.6 3105.6 3176.4 4639.7 4645.7 4690.2 4778.5 4857.8 4059.2 4059.4 4059.7 4063.2 4232.3 4728.8 4948.0 4980.4 4985.4 5225.2 3231.2 3236.4 3247.8 3249.6 3253.2 5215.8 5223.5 5233.8 5250.3 5282.7 3279.7 3287.9 3289.9 3318.3 3341.1 3175.6 3338.9 3339.6 3341.1 3362.2 3827.3 3954.8 3962.3 3973.2 4077.4 4999.4 5001.4 5007.9 5014.9 5159.8 1152.7 1162.7 1162.9 1164.9 1168.5 700.9 719.2 726.6 729.3 731.6 4457.8 4469.0 4559.7 4714.5 4854.3 5127.3 5205.6 5221.4 5229.5 5281.8 4901.5 4902.0 4911.0 4925.5 5062.7 2743.5 2761.2 2768.6 2782.2 2910.7 5360.7 5518.0 5522.8 5526.4 5692.1 5199.6 5277.8 5310.7 5363.9 5382.4 5511.4 5565.7 5566.7 5572.9 5734.5 5250.6 5253.3 5259.6 5348.7 5459.2 5105.4 5110.9 5114.0 5148.2 5148.9 5523.3 5551.6 5645.6 5645.9 5772.3 3240.9 3258.7 3266.0 3314.5 3375.8 5087.5 5104.5 5249.2 5273.4 5361.3 5073.2 5093.8 5115.6 5169.4 5294.5 5079.9 5087.6 5115.4 5235.7 5255.7 4978.3 5070.4 5096.2 5211.3 5265.6 5058.6 5085.3 5089.5 5096.5 5108.6 4772.4 4801.3 5016.2 5106.5 5152.9
23:52:29 INFO - PROCESS | 1100 |
23:52:29 INFO - PROCESS | 1100 | [#5] yui.html Cycles:180 Average:20294.97 Median:3762.75 stddev:30646.55 (814.5%) stddev-sans-first:30696.75
23:52:29 INFO - PROCESS | 1100 | Values: 575.3 593.5 600.8 609.0 641.2 13057.2 13443.7 13458.4 13558.4 13652.3 12192.3 12336.6 12464.8 12552.5 12923.1 568.8 574.6 575.2 585.1 585.6 479.3 566.9 568.5 569.1 577.1 497.4 600.8 607.9 614.5 631.1 596.8 597.7 600.3 604.0 613.4 361.4 434.2 435.9 437.8 449.0 10098.6 12061.5 12348.6 12825.3 13258.9 12955.3 13327.0 13406.7 13470.4 13788.0 10785.9 11170.3 11411.6 11429.3 11587.9 3359.4 3398.9 3480.7 3544.7 3558.9 80997.0 81728.0 82054.2 83486.4 84559.0 10081.4 10239.4 10294.4 10405.5 10607.2 18282.6 18664.5 18870.5 18943.3 19836.2 6866.3 7059.5 7066.8 7115.7 7148.6 3441.2 3619.5 3620.6 3737.8 3787.7 2738.9 2759.0 2782.2 2794.1 2816.8 515.8 518.9 521.2 522.6 525.7 507.9 558.7 575.8 643.3 658.5 8147.7 8187.3 8221.5 8748.0 8837.4 563.0 574.3 672.7 673.9 695.7 4259.9 4323.0 4397.1 4401.6 4429.2 111459.0 114736.0 122493.4 123463.0 124599.0 83482.6 92125.5 96464.6 100061.5 100658.5 87864.0 92175.1 92330.8 93449.0 93606.5 8409.8 8680.5 8861.5 8969.7 9155.3 47598.8 47961.5 48214.2 50317.0 50433.2 68371.3 69636.3 70037.0 71023.3 71698.6 12139.2 12292.1 12478.3 12492.1 12528.2 13677.0 13699.2 13991.0 14291.5 14568.6 12929.3 12950.2 13096.9 13274.5 13377.5 12788.4 12942.9 13153.7 13364.4 13759.9 12698.3 12942.2 13271.7 13285.4 13671.7 11010.5 11038.8 11050.3 11519.5 11862.6 9673.8 10304.9 10314.9 10594.6 10980.5
23:52:29 INFO - PROCESS | 1100 | -------- Summary: end --------
23:52:29 INFO - PROCESS | 1100 |
23:52:30 INFO - TEST-INFO | 1100: exit 0
23:52:31 INFO - TEST-OK | dromaeo_css | took 1122652ms
23:52:31 INFO - TEST-START | kraken
23:52:31 INFO - operating with platform_type : w7_
23:52:31 INFO - Initialising browser for kraken test...
23:52:31 INFO - TEST-INFO | started process 3984 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpiwhaxe\profile http://localhost:49621/getInfo.html)
23:52:35 INFO - PROCESS | 3984 | __metrics Screen width/height:1600/1200
23:52:35 INFO - PROCESS | 3984 | colorDepth:24
23:52:35 INFO - PROCESS | 3984 | Browser inner width/height: 1010/674
23:52:35 INFO - PROCESS | 3984 | __metrics
23:52:36 INFO - PROCESS | 3984 | 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"
23:52:36 INFO - PROCESS | 3984 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
23:52:36 INFO - PROCESS | 3984 | console.error:
23:52:36 INFO - PROCESS | 3984 | Message: Error: Connection closed before committing the transaction.
23:52:36 INFO - PROCESS | 3984 | Stack:
23:52:36 INFO - PROCESS | 3984 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:937:23
23:52:36 INFO - PROCESS | 3984 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7
23:52:36 INFO - PROCESS | 3984 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11
23:52:36 INFO - PROCESS | 3984 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7
23:52:36 INFO - PROCESS | 3984 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7
23:52:36 INFO - PROCESS | 3984 | TaskImpl_run@resource://gre/modules/Task.jsm:327:15
23:52:36 INFO - PROCESS | 3984 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9
23:52:36 INFO - PROCESS | 3984 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15
23:52:36 INFO - PROCESS | 3984 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5
23:52:36 INFO - PROCESS | 3984 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29
23:52:36 INFO - PROCESS | 3984 | Barrier/this.client.addBlocker/promise13.2%
23:53:35 INFO - TinderboxPrint: I/O read bytes / time
202,319,872 / 2,394,160
23:53:35 INFO - TinderboxPrint: I/O write bytes / time
493,215,744 / 3,584,430
23:53:35 INFO - TinderboxPrint: CPU idle
8,279.4 (86.8%)
23:53:35 INFO - TinderboxPrint: CPU user
1,215.8 (12.7%)
23:53:35 INFO - install - Wall time: 3s; CPU: 12.0%; Read bytes: 0; Write bytes: 34921984; Read time: 0; Write time: 246200
23:53:35 INFO - run-tests - Wall time: 1190s; CPU: 13.0%; Read bytes: 202319872; Write bytes: 421693440; Read time: 2394160; Write time: 3160920
23:53:35 INFO - Running post-run listener: _upload_blobber_files
23:53:35 INFO - Blob upload gear active.
23:53:35 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir.
23:53:35 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
23:53:35 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']
23:53:35 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
23:53:36 INFO - (blobuploader) - INFO - Open directory for files ...
23:53:36 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log ...
23:53:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
23:53:36 INFO - (blobuploader) - INFO - Uploading, attempt #1.
23:53:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded
23:53:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
23:53:36 INFO - (blobuploader) - INFO - Done attempting.
23:53:36 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log ...
23:53:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
23:53:36 INFO - (blobuploader) - INFO - Uploading, attempt #1.
23:53:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded
23:53:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
23:53:37 INFO - (blobuploader) - INFO - Done attempting.
23:53:37 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ...
23:53:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
23:53:37 INFO - (blobuploader) - INFO - Uploading, attempt #1.
23:53:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
23:53:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
23:53:45 INFO - (blobuploader) - INFO - Done attempting.
23:53:45 INFO - (blobuploader) - INFO - Iteration through files over.
23:53:45 INFO - Return code: 0
23:53:45 INFO - rmtree: C:\slave\test\build\uploaded_files.json
23:53:45 INFO - Using _rmtree_windows ...
23:53:45 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1
23:53:45 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/975b9de30f344bc198445ca6b93d6cb3887fa9531434e539eefd0ebad817d987a9f5f215867dc5ab498fa26844482aabcb4f8d8023042fb9ccb6ff45c29f8e36", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3d6075b81001734b0ab767e18331466c267012319dbd8db9ad6fca7a6d0c4e3872ff6cb52a152acdf35a1f541451a2e75c6558b37918edef24fb31d720303925", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6456fd9f683dbea927e71fa1d8609a4cd4f95e6521907521db95592bcee7841952dbf5f4a7c818fbe3081d00c7be4a636f084145fce8defeff2902bc70f2fb81"}
23:53:45 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files
23:53:45 INFO - Writing to file C:\slave\test\properties\blobber_files
23:53:45 INFO - Contents:
23:53:45 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/975b9de30f344bc198445ca6b93d6cb3887fa9531434e539eefd0ebad817d987a9f5f215867dc5ab498fa26844482aabcb4f8d8023042fb9ccb6ff45c29f8e36", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3d6075b81001734b0ab767e18331466c267012319dbd8db9ad6fca7a6d0c4e3872ff6cb52a152acdf35a1f541451a2e75c6558b37918edef24fb31d720303925", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6456fd9f683dbea927e71fa1d8609a4cd4f95e6521907521db95592bcee7841952dbf5f4a7c818fbe3081d00c7be4a636f084145fce8defeff2902bc70f2fb81"}
23:53:45 INFO - Running post-run listener: copy_logs_to_upload_dir
23:53:45 INFO - Copying logs to upload dir...
23:53:45 INFO - mkdir: C:\slave\test\build\upload\logs
23:53:45 INFO - Copying logs to upload dir...
23:53:45 INFO - Using _rmtree_windows ...
23:53:45 INFO - Using _rmtree_windows ...
23:53:45 INFO - Using _rmtree_windows ...
23:53:45 INFO - Using _rmtree_windows ...
23:53:45 INFO - Using _rmtree_windows ...
23:53:45 INFO - Using _rmtree_windows ...
23:53:45 INFO - Using _rmtree_windows ...
program finished with exit code 0
elapsedTime=1445.096000
========= master_lag: 3.17 =========
========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 8 secs) (at 2016-10-18 23:53:48.885666) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-18 23:53:48.892016) =========
'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-106
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-106
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-106
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/975b9de30f344bc198445ca6b93d6cb3887fa9531434e539eefd0ebad817d987a9f5f215867dc5ab498fa26844482aabcb4f8d8023042fb9ccb6ff45c29f8e36", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3d6075b81001734b0ab767e18331466c267012319dbd8db9ad6fca7a6d0c4e3872ff6cb52a152acdf35a1f541451a2e75c6558b37918edef24fb31d720303925", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6456fd9f683dbea927e71fa1d8609a4cd4f95e6521907521db95592bcee7841952dbf5f4a7c818fbe3081d00c7be4a636f084145fce8defeff2902bc70f2fb81"}
build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476851407/firefox-52.0a1.en-US.win32.zip
program finished with exit code 0
elapsedTime=0.102000
build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476851407/firefox-52.0a1.en-US.win32.zip'
blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/975b9de30f344bc198445ca6b93d6cb3887fa9531434e539eefd0ebad817d987a9f5f215867dc5ab498fa26844482aabcb4f8d8023042fb9ccb6ff45c29f8e36", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3d6075b81001734b0ab767e18331466c267012319dbd8db9ad6fca7a6d0c4e3872ff6cb52a152acdf35a1f541451a2e75c6558b37918edef24fb31d720303925", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6456fd9f683dbea927e71fa1d8609a4cd4f95e6521907521db95592bcee7841952dbf5f4a7c818fbe3081d00c7be4a636f084145fce8defeff2902bc70f2fb81"}'
========= master_lag: 0.08 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-18 23:53:49.071683) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 23:53:49.072002) =========
'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-106
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-106
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-106
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-18 23:53:49.184385) =========
========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-10-18 23:53:49.184692) =========
========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-10-18 23:53:49.702452) =========
========= Total master_lag: 3.34 =========