builder: fx-team_win7_ix_test-dromaeojs-pgo slave: t-w732-ix-110 starttime: 1477014704.3 results: success (0) buildid: 20161020184349 builduid: 52099b9144bc49f7a7e683e4c2495881 revision: ba6fc133264d8e7aa8adf43d9d88f48b6dc2ffac ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-20 18:51:44.304307) ========= master: http://buildbot-master111.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-20 18:51:44.304749) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-20 18:51:44.305050) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-20 18:51:44.476552) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2016-10-20 18:51:44.476944) ========= '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-110 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-110 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-110 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 --18:51:45-- 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% 2.27 GB/s 18:51:45 (2.27 GB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=1.506000 ========= master_lag: 3.70 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2016-10-20 18:51:49.679392) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 15 secs) (at 2016-10-20 18:51:49.679774) ========= '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-110 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-110 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-110 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=11.601000 ========= master_lag: 4.29 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 15 secs) (at 2016-10-20 18:52:05.571134) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-20 18:52:05.571513) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo integration/fx-team --rev ba6fc133264d8e7aa8adf43d9d88f48b6dc2ffac --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 ba6fc133264d8e7aa8adf43d9d88f48b6dc2ffac --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-110 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-110 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-110 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-20 18:52:06,051 truncating revision to first 12 chars 2016-10-20 18:52:06,051 Setting DEBUG logging. 2016-10-20 18:52:06,051 attempt 1/10 2016-10-20 18:52:06,051 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/ba6fc133264d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-20 18:52:06,592 unpacking tar archive at: fx-team-ba6fc133264d/testing/mozharness/ 2016-10-20 18:52:06,714 skipping symlink on windows: configs/single_locale/linux32.py program finished with exit code 0 elapsedTime=1.397000 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-20 18:52:07.030958) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-20 18:52:07.031296) ========= script_repo_revision: ba6fc133264d8e7aa8adf43d9d88f48b6dc2ffac ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-20 18:52:07.031740) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-20 18:52:07.032029) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-20 18:52:07.043054) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 44 secs) (at 2016-10-20 18:52:07.043452) ========= '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-110 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-110 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-110 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 18:52:07 INFO - MultiFileLogger online at 20161020 18:52:07 in C:\slave\test 18:52:07 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 18:52:07 INFO - Dumping config to C:\slave\test\logs\localconfig.json. 18:52:07 INFO - {'append_to_log': False, 18:52:07 INFO - 'base_work_dir': 'C:\\slave\\test', 18:52:07 INFO - 'blob_upload_branch': 'Fx-Team', 18:52:07 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt', 18:52:07 INFO - 'branch': 'Fx-Team', 18:52:07 INFO - 'buildbot_json_path': 'buildprops.json', 18:52:07 INFO - 'config_files': ('talos/windows_config.py',), 18:52:07 INFO - 'default_actions': ('clobber', 18:52:07 INFO - 'read-buildbot-config', 18:52:07 INFO - 'download-and-extract', 18:52:07 INFO - 'populate-webroot', 18:52:07 INFO - 'create-virtualenv', 18:52:07 INFO - 'install', 18:52:07 INFO - 'run-tests'), 18:52:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:52:07 INFO - 'download_minidump_stackwalk': True, 18:52:07 INFO - 'download_symbols': 'ondemand', 18:52:07 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python', 18:52:07 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'), 18:52:07 INFO - 'hg': 'c:/mozilla-build/hg/hg', 18:52:07 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python', 18:52:07 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'), 18:52:07 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 18:52:07 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 18:52:07 INFO - 'C:/mozilla-build/tooltool.py'), 18:52:07 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 18:52:07 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 18:52:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:52:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:52:07 INFO - 'installer_path': 'installer.exe', 18:52:07 INFO - 'log_level': 'info', 18:52:07 INFO - 'log_name': 'talos', 18:52:07 INFO - 'log_to_console': True, 18:52:07 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 18:52:07 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 18:52:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 18:52:07 INFO - 'opt_config_files': (), 18:52:07 INFO - 'pip_index': False, 18:52:07 INFO - 'sps_profile': False, 18:52:07 INFO - 'sps_profile_interval': 0, 18:52:07 INFO - 'suite': 'dromaeojs', 18:52:07 INFO - 'system_bits': '32', 18:52:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:52:07 INFO - 'title': 'T-W732-IX-110', 18:52:07 INFO - 'use_talos_json': True, 18:52:07 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 18:52:07 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv', 18:52:07 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 18:52:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:52:07 INFO - 'work_dir': 'build'} 18:52:07 INFO - [mozharness: 2016-10-21 01:52:07.642000Z] Running clobber step. 18:52:07 INFO - Running pre-action listener: _resource_record_pre_action 18:52:07 INFO - Running main action method: clobber 18:52:07 INFO - rmtree: C:\slave\test\build 18:52:07 INFO - Using _rmtree_windows ... 18:52:07 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1 18:55:13 INFO - Running post-action listener: _resource_record_post_action 18:55:13 INFO - [mozharness: 2016-10-21 01:55:13.629000Z] Finished clobber step (success) 18:55:13 INFO - [mozharness: 2016-10-21 01:55:13.629000Z] Running read-buildbot-config step. 18:55:13 INFO - Running pre-action listener: _resource_record_pre_action 18:55:13 INFO - Running main action method: read_buildbot_config 18:55:13 INFO - Using buildbot properties: 18:55:13 INFO - { 18:55:13 INFO - "project": "", 18:55:13 INFO - "product": "firefox", 18:55:13 INFO - "script_repo_revision": "production", 18:55:13 INFO - "builddir": "fx-team_win7_ix_test-dromaeojs", 18:55:13 INFO - "repository": "", 18:55:13 INFO - "buildername": "Windows 7 32-bit fx-team pgo talos dromaeojs", 18:55:13 INFO - "buildid": "20161020184349", 18:55:13 INFO - "stage_platform": "win32-pgo", 18:55:13 INFO - "basedir": "C:\\slave\\test", 18:55:13 INFO - "buildnumber": 244, 18:55:13 INFO - "platform": "win7_ix", 18:55:13 INFO - "master": "http://buildbot-master111.bb.releng.scl3.mozilla.com:8201/", 18:55:13 INFO - "slavebuilddir": "test", 18:55:13 INFO - "branch": "fx-team", 18:55:13 INFO - "revision": "ba6fc133264d8e7aa8adf43d9d88f48b6dc2ffac", 18:55:13 INFO - "slavename": "t-w732-ix-110", 18:55:13 INFO - "builduid": "52099b9144bc49f7a7e683e4c2495881", 18:55:13 INFO - "repo_path": "integration/fx-team" 18:55:13 INFO - } 18:55:13 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477002622/firefox-52.0a1.en-US.win32.test_packages.json. 18:55:13 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477002622/firefox-52.0a1.en-US.win32.zip. 18:55:13 INFO - Running post-action listener: _resource_record_post_action 18:55:13 INFO - [mozharness: 2016-10-21 01:55:13.633000Z] Finished read-buildbot-config step (success) 18:55:13 INFO - [mozharness: 2016-10-21 01:55:13.633000Z] Running download-and-extract step. 18:55:13 INFO - Running pre-action listener: _resource_record_pre_action 18:55:13 INFO - Running main action method: download_and_extract 18:55:13 INFO - mkdir: C:\slave\test\build\tests 18:55:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:55:13 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477002622/firefox-52.0a1.en-US.win32.test_packages.json 18:55:13 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477002622/firefox-52.0a1.en-US.win32.test_packages.json to C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json 18:55:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477002622/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 18:55:14 INFO - Downloaded 1218 bytes. 18:55:14 INFO - Reading from file C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json 18:55:14 INFO - Using the following test package requirements: 18:55:14 INFO - {u'common': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 18:55:14 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 18:55:14 INFO - u'firefox-52.0a1.en-US.win32.cppunittest.tests.zip'], 18:55:14 INFO - u'gtest': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 18:55:14 INFO - u'jittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 18:55:14 INFO - u'jsshell-win32.zip'], 18:55:14 INFO - u'mochitest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 18:55:14 INFO - u'firefox-52.0a1.en-US.win32.mochitest.tests.zip'], 18:55:14 INFO - u'mozbase': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 18:55:14 INFO - u'reftest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 18:55:14 INFO - u'firefox-52.0a1.en-US.win32.reftest.tests.zip'], 18:55:14 INFO - u'talos': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 18:55:14 INFO - u'firefox-52.0a1.en-US.win32.talos.tests.zip'], 18:55:14 INFO - u'web-platform': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 18:55:14 INFO - u'firefox-52.0a1.en-US.win32.web-platform.tests.zip'], 18:55:14 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 18:55:14 INFO - u'firefox-52.0a1.en-US.win32.xpcshell.tests.zip']} 18:55:14 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip'] for test suite category: common 18:55:14 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/1477002622/firefox-52.0a1.en-US.win32.common.tests.zip 18:55:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477002622/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1 18:55:14 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477002622/firefox-52.0a1.en-US.win32.common.tests.zip into memory 18:55:14 INFO - Http code: 200 18:55:14 INFO - via: 1.1 e1eb7447ab46f530f6009f1d93917c54.cloudfront.net (CloudFront) 18:55:14 INFO - x-amz-cf-id: biGGMjyccg05KCjqs1MH5_vSJUzRZThdE2qS4f75jAgtUEtypM_nlw== 18:55:14 INFO - x-amz-replication-status: COMPLETED 18:55:14 INFO - x-amz-version-id: JuVPNT9o6G4OvH6xgQ6YAKS24VnD3pfr 18:55:15 INFO - Expected file size: 17265192 18:55:15 INFO - Obtained file size: 17265192 18:55:41 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 18:55:41 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/1477002622/firefox-52.0a1.en-US.win32.common.tests.zip 18:55:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477002622/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1 18:55:41 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477002622/firefox-52.0a1.en-US.win32.common.tests.zip into memory 18:55:41 INFO - Http code: 200 18:55:41 INFO - via: 1.1 24b0e5a3429d07ef12381da50e07f70f.cloudfront.net (CloudFront) 18:55:41 INFO - x-amz-cf-id: Qf5JAk8etbPB01N6dBuna6VT93ujzN51VhhxpFNIxw_zVviY1r03Vg== 18:55:41 INFO - x-amz-replication-status: COMPLETED 18:55:41 INFO - x-amz-version-id: JuVPNT9o6G4OvH6xgQ6YAKS24VnD3pfr 18:55:41 INFO - Expected file size: 17265192 18:55:41 INFO - Obtained file size: 17265192 18:55:51 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/1477002622/firefox-52.0a1.en-US.win32.talos.tests.zip 18:55:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477002622/firefox-52.0a1.en-US.win32.talos.tests.zip'}, attempt #1 18:55:51 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477002622/firefox-52.0a1.en-US.win32.talos.tests.zip into memory 18:55:51 INFO - Http code: 200 18:55:51 INFO - via: 1.1 bfc29822e21ecc352f71dce83e06ab74.cloudfront.net (CloudFront) 18:55:51 INFO - x-amz-cf-id: PEChWMKrIIy16pK_YwVOjba9PMOMaIWfQ5b9Mf_j1MCnlENgS5fc0A== 18:55:51 INFO - x-amz-replication-status: COMPLETED 18:55:51 INFO - x-amz-version-id: 5jSDAD0RR5xhliGYCuJDnVh6Lh7clytQ 18:55:52 INFO - Expected file size: 14409028 18:55:52 INFO - Obtained file size: 14409028 18:55:52 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')]} 18:55:52 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477002622/firefox-52.0a1.en-US.win32.zip 18:55:52 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477002622/firefox-52.0a1.en-US.win32.zip to C:\slave\test\build\firefox-52.0a1.en-US.win32.zip 18:55:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477002622/firefox-52.0a1.en-US.win32.zip', 'file_name': 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.zip'}, attempt #1 18:55:54 INFO - Downloaded 54324330 bytes. 18:55:54 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477002622/firefox-52.0a1.en-US.win32.zip 18:55:54 INFO - mkdir: C:\slave\test\properties 18:55:54 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url 18:55:54 INFO - Writing to file C:\slave\test\properties\build_url 18:55:54 INFO - Contents: 18:55:54 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477002622/firefox-52.0a1.en-US.win32.zip 18:55:54 INFO - Running post-action listener: _resource_record_post_action 18:55:54 INFO - Running post-action listener: set_extra_try_arguments 18:55:54 INFO - [mozharness: 2016-10-21 01:55:54.481000Z] Finished download-and-extract step (success) 18:55:54 INFO - [mozharness: 2016-10-21 01:55:54.481000Z] Running populate-webroot step. 18:55:54 INFO - Running pre-action listener: _resource_record_pre_action 18:55:54 INFO - Running main action method: populate_webroot 18:55:54 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 18:55:54 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 18:55:54 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 18:55:54 INFO - u'--tppagecycles', 18:55:54 INFO - u'7'], 18:55:54 INFO - u'tests': [u'tsvgm']}}, 18:55:54 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'], 18:55:54 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 18:55:54 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']}, 18:55:54 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'], 18:55:54 INFO - u'tests': [u'dromaeo_css', u'kraken']}, 18:55:54 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']}, 18:55:54 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:55:54 INFO - u'talos_options': [u'--disable-e10s'], 18:55:54 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 18:55:54 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:55:54 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 18:55:54 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:55:54 INFO - u'talos_options': [u'--disable-e10s'], 18:55:54 INFO - u'tests': [u'damp', u'tps']}, 18:55:54 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:55:54 INFO - u'tests': [u'damp', u'tps']}, 18:55:54 INFO - u'g3': {u'talos_options': [u'--disable-e10s'], 18:55:54 INFO - u'tests': [u'dromaeo_dom']}, 18:55:54 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 18:55:54 INFO - u'g4': {u'talos_options': [u'--disable-e10s'], 18:55:54 INFO - u'tests': [u'basic_compositor_video']}, 18:55:54 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']}, 18:55:54 INFO - u'other': {u'talos_options': [u'--disable-e10s'], 18:55:54 INFO - u'tests': [u'a11yr', 18:55:54 INFO - u'ts_paint', 18:55:54 INFO - u'tpaint', 18:55:54 INFO - u'sessionrestore', 18:55:54 INFO - u'sessionrestore_no_auto_restore', 18:55:54 INFO - u'tabpaint']}, 18:55:54 INFO - u'other-e10s': {u'tests': [u'a11yr', 18:55:54 INFO - u'ts_paint', 18:55:54 INFO - u'tpaint', 18:55:54 INFO - u'sessionrestore', 18:55:54 INFO - u'sessionrestore_no_auto_restore', 18:55:54 INFO - u'tabpaint']}, 18:55:54 INFO - u'other-e10s_l64': {u'tests': [u'a11yr', 18:55:54 INFO - u'ts_paint', 18:55:54 INFO - u'tpaint', 18:55:54 INFO - u'sessionrestore', 18:55:54 INFO - u'sessionrestore_no_auto_restore', 18:55:54 INFO - u'tabpaint']}, 18:55:54 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr', 18:55:54 INFO - u'ts_paint', 18:55:54 INFO - u'tpaint', 18:55:54 INFO - u'sessionrestore', 18:55:54 INFO - u'sessionrestore_no_auto_restore', 18:55:54 INFO - u'tabpaint']}, 18:55:54 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'], 18:55:54 INFO - u'tests': [u'a11yr', 18:55:54 INFO - u'ts_paint', 18:55:54 INFO - u'tpaint', 18:55:54 INFO - u'sessionrestore', 18:55:54 INFO - u'sessionrestore_no_auto_restore', 18:55:54 INFO - u'tabpaint']}, 18:55:54 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'], 18:55:54 INFO - u'tests': [u'a11yr', 18:55:54 INFO - u'ts_paint', 18:55:54 INFO - u'tpaint', 18:55:54 INFO - u'sessionrestore', 18:55:54 INFO - u'sessionrestore_no_auto_restore', 18:55:54 INFO - u'tabpaint']}, 18:55:54 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'], 18:55:54 INFO - u'tests': [u'tsvgx', 18:55:54 INFO - u'tsvgr_opacity', 18:55:54 INFO - u'tart', 18:55:54 INFO - u'tscrollx', 18:55:54 INFO - u'cart']}, 18:55:54 INFO - u'svgr-e10s': {u'tests': [u'tsvgx', 18:55:54 INFO - u'tsvgr_opacity', 18:55:54 INFO - u'tart', 18:55:54 INFO - u'tscrollx', 18:55:54 INFO - u'cart']}, 18:55:54 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:55:54 INFO - u'talos_options': [u'--disable-e10s'], 18:55:54 INFO - u'tests': [u'tp5o']}, 18:55:54 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:55:54 INFO - u'tests': [u'tp5o']}, 18:55:54 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:55:54 INFO - u'talos_options': [u'--disable-e10s', 18:55:54 INFO - u'--xperf_path', 18:55:54 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:55:54 INFO - u'tests': [u'tp5n']}, 18:55:54 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:55:54 INFO - u'talos_options': [u'--xperf_path', 18:55:54 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:55:54 INFO - u'tests': [u'tp5n']}}, 18:55:54 INFO - u'talos.zip': {u'path': u'', 18:55:54 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:55:54 INFO - Running post-action listener: _resource_record_post_action 18:55:54 INFO - [mozharness: 2016-10-21 01:55:54.521000Z] Finished populate-webroot step (success) 18:55:54 INFO - [mozharness: 2016-10-21 01:55:54.521000Z] Running create-virtualenv step. 18:55:54 INFO - Running pre-action listener: _resource_record_pre_action 18:55:54 INFO - Running main action method: create_virtualenv 18:55:54 INFO - Creating virtualenv C:\slave\test\build/venv 18:55:54 INFO - mkdir: C:\slave\test\build\venv\Scripts 18:55:54 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll 18:55:54 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'! 18:55:54 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 18:55:54 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv 18:56:07 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe 18:56:11 INFO - Installing distribute......................................................................................................................................................................................done. 18:56:12 INFO - Return code: 0 18:56:12 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version'] 18:56:12 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version 18:56:12 INFO - Reading from file tmpfile_stdout 18:56:12 INFO - Output received: 18:56:12 INFO - pip 0.8.2 from c:\slave\test\build\venv\lib\site-packages\pip-0.8.2-py2.7.egg (python 2.7) 18:56:12 INFO - Using _rmtree_windows ... 18:56:12 INFO - Using _rmtree_windows ... 18:56:12 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv 18:56: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')]} 18:56:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:13 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 0x0184B640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C6E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017C2B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018A0E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01FE5520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-110', 'USERDOMAIN': 'T-W732-IX-110', '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-110', '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 18:56:13 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 18:56:13 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 18:56:13 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 18:56:13 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 18:56:13 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 18:56:13 INFO - 'COMPUTERNAME': 'T-W732-IX-110', 18:56:13 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 18:56:13 INFO - 'CYGWINBASE': 'C:\\cygwin', 18:56:13 INFO - 'DCLOCATION': 'SCL3', 18:56:13 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 18:56:13 INFO - 'FP_NO_HOST_CHECK': 'NO', 18:56:13 INFO - 'HOMEDRIVE': 'C:', 18:56:13 INFO - 'HOMEPATH': '\\Users\\cltbld', 18:56:13 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 18:56:13 INFO - 'KTS_VERSION': '1.19c', 18:56:13 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 18:56:13 INFO - 'LOGONSERVER': '\\\\T-W732-IX-110', 18:56:13 INFO - 'MONDIR': 'C:\\Monitor_config\\', 18:56:13 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 18:56:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:13 INFO - 'MOZ_NO_REMOTE': '1', 18:56:13 INFO - 'NO_EM_RESTART': '1', 18:56:13 INFO - 'NUMBER_OF_PROCESSORS': '8', 18:56:13 INFO - 'OS': 'Windows_NT', 18:56:13 INFO - 'OURDRIVE': 'C:', 18:56:13 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 18:56:13 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 18:56:13 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 18:56:13 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 18:56:13 INFO - 'PROCESSOR_LEVEL': '6', 18:56:13 INFO - 'PROCESSOR_REVISION': '1e05', 18:56:13 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 18:56:13 INFO - 'PROGRAMFILES': 'C:\\Program Files', 18:56:13 INFO - 'PROMPT': '$P$G', 18:56:13 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 18:56:13 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 18:56:13 INFO - 'PUBLIC': 'C:\\Users\\Public', 18:56:13 INFO - 'PWD': 'C:\\slave\\test', 18:56:13 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 18:56:13 INFO - 'SLAVEDIR': 'C:\\slave\\', 18:56:13 INFO - 'SYSTEMDRIVE': 'C:', 18:56:13 INFO - 'SYSTEMROOT': 'C:\\windows', 18:56:13 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:56:13 INFO - 'TEST1': 'testie', 18:56:13 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:56:13 INFO - 'USERDOMAIN': 'T-W732-IX-110', 18:56:13 INFO - 'USERNAME': 'cltbld', 18:56:13 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 18:56:13 INFO - 'WINDIR': 'C:\\windows', 18:56:13 INFO - 'WINDOWS_TRACING_FLAGS': '3', 18:56:13 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 18:56:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 18:56:14 INFO - Ignoring indexes: http://pypi.python.org/simple/ 18:56:14 INFO - Downloading/unpacking pip>=1.5 18:56:18 INFO - Running setup.py egg_info for package pip 18:56:18 INFO - warning: no files found matching 'pip\cacert.pem' 18:56:18 INFO - warning: no files found matching '*.html' under directory 'docs' 18:56:18 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 18:56:18 INFO - no previously-included directories found matching 'docs\_build\_sources' 18:56:18 INFO - Installing collected packages: pip 18:56:18 INFO - Found existing installation: pip 0.8.2 18:56:18 INFO - Uninstalling pip: 18:56:18 INFO - Successfully uninstalled pip 18:56:18 INFO - Running setup.py install for pip 18:56:18 INFO - warning: no files found matching 'pip\cacert.pem' 18:56:18 INFO - warning: no files found matching '*.html' under directory 'docs' 18:56:18 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 18:56:18 INFO - no previously-included directories found matching 'docs\_build\_sources' 18:56:18 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts 18:56:18 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts 18:56:18 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts 18:56:18 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts 18:56:18 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts 18:56:18 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts 18:56:18 INFO - Successfully installed pip 18:56:18 INFO - Cleaning up... 18:56:18 INFO - Return code: 0 18:56:18 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv 18:56: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')]} 18:56:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56: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')]} 18:56:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56: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', '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 0x0184B640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C6E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017C2B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018A0E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01FE5520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-110', 'USERDOMAIN': 'T-W732-IX-110', '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-110', '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 18:56: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', 'psutil>=3.1.1'] in C:\slave\test\build 18:56: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 psutil>=3.1.1 18:56:18 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 18:56:18 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 18:56:18 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 18:56:18 INFO - 'COMPUTERNAME': 'T-W732-IX-110', 18:56:18 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 18:56:18 INFO - 'CYGWINBASE': 'C:\\cygwin', 18:56:18 INFO - 'DCLOCATION': 'SCL3', 18:56:18 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 18:56:18 INFO - 'FP_NO_HOST_CHECK': 'NO', 18:56:18 INFO - 'HOMEDRIVE': 'C:', 18:56:18 INFO - 'HOMEPATH': '\\Users\\cltbld', 18:56:18 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 18:56:18 INFO - 'KTS_VERSION': '1.19c', 18:56:18 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 18:56:18 INFO - 'LOGONSERVER': '\\\\T-W732-IX-110', 18:56:18 INFO - 'MONDIR': 'C:\\Monitor_config\\', 18:56:18 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 18:56:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:18 INFO - 'MOZ_NO_REMOTE': '1', 18:56:18 INFO - 'NO_EM_RESTART': '1', 18:56:18 INFO - 'NUMBER_OF_PROCESSORS': '8', 18:56:18 INFO - 'OS': 'Windows_NT', 18:56:18 INFO - 'OURDRIVE': 'C:', 18:56: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;', 18:56:18 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 18:56:18 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 18:56:18 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 18:56:18 INFO - 'PROCESSOR_LEVEL': '6', 18:56:18 INFO - 'PROCESSOR_REVISION': '1e05', 18:56:18 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 18:56:18 INFO - 'PROGRAMFILES': 'C:\\Program Files', 18:56:18 INFO - 'PROMPT': '$P$G', 18:56:18 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 18:56:18 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 18:56:18 INFO - 'PUBLIC': 'C:\\Users\\Public', 18:56:18 INFO - 'PWD': 'C:\\slave\\test', 18:56:18 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 18:56:18 INFO - 'SLAVEDIR': 'C:\\slave\\', 18:56:18 INFO - 'SYSTEMDRIVE': 'C:', 18:56:18 INFO - 'SYSTEMROOT': 'C:\\windows', 18:56:18 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:56:18 INFO - 'TEST1': 'testie', 18:56:18 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:56:18 INFO - 'USERDOMAIN': 'T-W732-IX-110', 18:56:18 INFO - 'USERNAME': 'cltbld', 18:56:18 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 18:56:18 INFO - 'WINDIR': 'C:\\windows', 18:56:18 INFO - 'WINDOWS_TRACING_FLAGS': '3', 18:56:18 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 18:56:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 18:56:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:19 INFO - Downloading/unpacking psutil>=3.1.1 18:56: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 18:56: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 18:56:21 INFO - Installing collected packages: psutil 18:56:21 INFO - Successfully installed psutil 18:56:21 INFO - Cleaning up... 18:56:21 INFO - Return code: 0 18:56:21 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv 18:56:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:21 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0184B640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C6E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017C2B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018A0E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01FE5520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-110', 'USERDOMAIN': 'T-W732-IX-110', '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-110', '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 18:56:21 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in C:\slave\test\build 18:56:21 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:56:21 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 18:56:21 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 18:56:21 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 18:56:21 INFO - 'COMPUTERNAME': 'T-W732-IX-110', 18:56:21 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 18:56:21 INFO - 'CYGWINBASE': 'C:\\cygwin', 18:56:21 INFO - 'DCLOCATION': 'SCL3', 18:56:21 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 18:56:21 INFO - 'FP_NO_HOST_CHECK': 'NO', 18:56:21 INFO - 'HOMEDRIVE': 'C:', 18:56:21 INFO - 'HOMEPATH': '\\Users\\cltbld', 18:56:21 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 18:56:21 INFO - 'KTS_VERSION': '1.19c', 18:56:21 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 18:56:21 INFO - 'LOGONSERVER': '\\\\T-W732-IX-110', 18:56:21 INFO - 'MONDIR': 'C:\\Monitor_config\\', 18:56:21 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 18:56:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:21 INFO - 'MOZ_NO_REMOTE': '1', 18:56:21 INFO - 'NO_EM_RESTART': '1', 18:56:21 INFO - 'NUMBER_OF_PROCESSORS': '8', 18:56:21 INFO - 'OS': 'Windows_NT', 18:56:21 INFO - 'OURDRIVE': 'C:', 18:56:21 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 18:56:21 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 18:56:21 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 18:56:21 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 18:56:21 INFO - 'PROCESSOR_LEVEL': '6', 18:56:21 INFO - 'PROCESSOR_REVISION': '1e05', 18:56:21 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 18:56:21 INFO - 'PROGRAMFILES': 'C:\\Program Files', 18:56:21 INFO - 'PROMPT': '$P$G', 18:56:21 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 18:56:21 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 18:56:21 INFO - 'PUBLIC': 'C:\\Users\\Public', 18:56:21 INFO - 'PWD': 'C:\\slave\\test', 18:56:21 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 18:56:21 INFO - 'SLAVEDIR': 'C:\\slave\\', 18:56:21 INFO - 'SYSTEMDRIVE': 'C:', 18:56:21 INFO - 'SYSTEMROOT': 'C:\\windows', 18:56:21 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:56:21 INFO - 'TEST1': 'testie', 18:56:21 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:56:21 INFO - 'USERDOMAIN': 'T-W732-IX-110', 18:56:21 INFO - 'USERNAME': 'cltbld', 18:56:21 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 18:56:21 INFO - 'WINDIR': 'C:\\windows', 18:56:21 INFO - 'WINDOWS_TRACING_FLAGS': '3', 18:56:21 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 18:56:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 18:56:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:56:21 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 18:56:21 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 18:56:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:56:22 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 18:56:22 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) 18:56:22 INFO - Installing collected packages: mozsystemmonitor 18:56:22 INFO - Running setup.py install for mozsystemmonitor 18:56:23 INFO - Successfully installed mozsystemmonitor 18:56:23 INFO - Cleaning up... 18:56:24 INFO - Return code: 0 18:56:24 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv 18:56:24 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')]} 18:56:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:24 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')]} 18:56:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:24 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 0x0184B640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C6E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017C2B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018A0E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01FE5520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-110', 'USERDOMAIN': 'T-W732-IX-110', '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-110', '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 18:56:24 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 18:56:24 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 18:56:24 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 18:56:24 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 18:56:24 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 18:56:24 INFO - 'COMPUTERNAME': 'T-W732-IX-110', 18:56:24 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 18:56:24 INFO - 'CYGWINBASE': 'C:\\cygwin', 18:56:24 INFO - 'DCLOCATION': 'SCL3', 18:56:24 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 18:56:24 INFO - 'FP_NO_HOST_CHECK': 'NO', 18:56:24 INFO - 'HOMEDRIVE': 'C:', 18:56:24 INFO - 'HOMEPATH': '\\Users\\cltbld', 18:56:24 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 18:56:24 INFO - 'KTS_VERSION': '1.19c', 18:56:24 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 18:56:24 INFO - 'LOGONSERVER': '\\\\T-W732-IX-110', 18:56:24 INFO - 'MONDIR': 'C:\\Monitor_config\\', 18:56:24 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 18:56:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:24 INFO - 'MOZ_NO_REMOTE': '1', 18:56:24 INFO - 'NO_EM_RESTART': '1', 18:56:24 INFO - 'NUMBER_OF_PROCESSORS': '8', 18:56:24 INFO - 'OS': 'Windows_NT', 18:56:24 INFO - 'OURDRIVE': 'C:', 18:56:24 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;', 18:56:24 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 18:56:24 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 18:56:24 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 18:56:24 INFO - 'PROCESSOR_LEVEL': '6', 18:56:24 INFO - 'PROCESSOR_REVISION': '1e05', 18:56:24 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 18:56:24 INFO - 'PROGRAMFILES': 'C:\\Program Files', 18:56:24 INFO - 'PROMPT': '$P$G', 18:56:24 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 18:56:24 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 18:56:24 INFO - 'PUBLIC': 'C:\\Users\\Public', 18:56:24 INFO - 'PWD': 'C:\\slave\\test', 18:56:24 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 18:56:24 INFO - 'SLAVEDIR': 'C:\\slave\\', 18:56:24 INFO - 'SYSTEMDRIVE': 'C:', 18:56:24 INFO - 'SYSTEMROOT': 'C:\\windows', 18:56:24 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:56:24 INFO - 'TEST1': 'testie', 18:56:24 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:56:24 INFO - 'USERDOMAIN': 'T-W732-IX-110', 18:56:24 INFO - 'USERNAME': 'cltbld', 18:56:24 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 18:56:24 INFO - 'WINDIR': 'C:\\windows', 18:56:24 INFO - 'WINDOWS_TRACING_FLAGS': '3', 18:56:24 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 18:56:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 18:56:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:24 INFO - Downloading/unpacking jsonschema==2.5.1 18:56:24 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 18:56:24 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 18:56:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:56:25 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:56:25 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 18:56:25 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 18:56:25 INFO - Downloading functools32-3.2.3-2.tar.gz 18:56:25 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32 18:56:25 INFO - warning: no files found matching '*.txt' 18:56:25 INFO - no previously-included directories found matching 'build' 18:56:25 INFO - no previously-included directories found matching 'dist' 18:56:25 INFO - no previously-included directories found matching '.git*' 18:56:25 INFO - Installing collected packages: jsonschema, functools32 18:56:25 INFO - Running setup.py install for functools32 18:56:26 INFO - warning: no files found matching '*.txt' 18:56:26 INFO - no previously-included directories found matching 'build' 18:56:26 INFO - no previously-included directories found matching 'dist' 18:56:26 INFO - no previously-included directories found matching '.git*' 18:56:26 INFO - Successfully installed jsonschema functools32 18:56:26 INFO - Cleaning up... 18:56:26 INFO - Return code: 0 18:56:26 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv 18:56:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:26 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x0184B640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C6E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017C2B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018A0E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01FE5520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-110', 'USERDOMAIN': 'T-W732-IX-110', '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-110', '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 18:56:26 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in C:\slave\test\build 18:56:26 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:56:26 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 18:56:26 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 18:56:26 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 18:56:26 INFO - 'COMPUTERNAME': 'T-W732-IX-110', 18:56:26 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 18:56:26 INFO - 'CYGWINBASE': 'C:\\cygwin', 18:56:26 INFO - 'DCLOCATION': 'SCL3', 18:56:26 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 18:56:26 INFO - 'FP_NO_HOST_CHECK': 'NO', 18:56:26 INFO - 'HOMEDRIVE': 'C:', 18:56:26 INFO - 'HOMEPATH': '\\Users\\cltbld', 18:56:26 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 18:56:26 INFO - 'KTS_VERSION': '1.19c', 18:56:26 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 18:56:26 INFO - 'LOGONSERVER': '\\\\T-W732-IX-110', 18:56:26 INFO - 'MONDIR': 'C:\\Monitor_config\\', 18:56:26 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 18:56:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:26 INFO - 'MOZ_NO_REMOTE': '1', 18:56:26 INFO - 'NO_EM_RESTART': '1', 18:56:26 INFO - 'NUMBER_OF_PROCESSORS': '8', 18:56:26 INFO - 'OS': 'Windows_NT', 18:56:26 INFO - 'OURDRIVE': 'C:', 18:56:26 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 18:56:26 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 18:56:26 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 18:56:26 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 18:56:26 INFO - 'PROCESSOR_LEVEL': '6', 18:56:26 INFO - 'PROCESSOR_REVISION': '1e05', 18:56:26 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 18:56:26 INFO - 'PROGRAMFILES': 'C:\\Program Files', 18:56:26 INFO - 'PROMPT': '$P$G', 18:56:26 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 18:56:26 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 18:56:26 INFO - 'PUBLIC': 'C:\\Users\\Public', 18:56:26 INFO - 'PWD': 'C:\\slave\\test', 18:56:26 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 18:56:26 INFO - 'SLAVEDIR': 'C:\\slave\\', 18:56:26 INFO - 'SYSTEMDRIVE': 'C:', 18:56:26 INFO - 'SYSTEMROOT': 'C:\\windows', 18:56:26 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:56:26 INFO - 'TEST1': 'testie', 18:56:26 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:56:26 INFO - 'USERDOMAIN': 'T-W732-IX-110', 18:56:26 INFO - 'USERNAME': 'cltbld', 18:56:26 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 18:56:26 INFO - 'WINDIR': 'C:\\windows', 18:56:26 INFO - 'WINDOWS_TRACING_FLAGS': '3', 18:56:26 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 18:56:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 18:56:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:26 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages 18:56:26 INFO - Cleaning up... 18:56:27 INFO - Return code: 0 18:56:27 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv 18:56:27 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')]} 18:56:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:27 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')]} 18:56:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:27 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 0x0184B640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C6E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017C2B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018A0E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01FE5520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-110', 'USERDOMAIN': 'T-W732-IX-110', '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-110', '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 18:56:27 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 18:56:27 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 18:56:27 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 18:56:27 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 18:56:27 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 18:56:27 INFO - 'COMPUTERNAME': 'T-W732-IX-110', 18:56:27 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 18:56:27 INFO - 'CYGWINBASE': 'C:\\cygwin', 18:56:27 INFO - 'DCLOCATION': 'SCL3', 18:56:27 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 18:56:27 INFO - 'FP_NO_HOST_CHECK': 'NO', 18:56:27 INFO - 'HOMEDRIVE': 'C:', 18:56:27 INFO - 'HOMEPATH': '\\Users\\cltbld', 18:56:27 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 18:56:27 INFO - 'KTS_VERSION': '1.19c', 18:56:27 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 18:56:27 INFO - 'LOGONSERVER': '\\\\T-W732-IX-110', 18:56:27 INFO - 'MONDIR': 'C:\\Monitor_config\\', 18:56:27 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 18:56:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:27 INFO - 'MOZ_NO_REMOTE': '1', 18:56:27 INFO - 'NO_EM_RESTART': '1', 18:56:27 INFO - 'NUMBER_OF_PROCESSORS': '8', 18:56:27 INFO - 'OS': 'Windows_NT', 18:56:27 INFO - 'OURDRIVE': 'C:', 18:56:27 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;', 18:56:27 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 18:56:27 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 18:56:27 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 18:56:27 INFO - 'PROCESSOR_LEVEL': '6', 18:56:27 INFO - 'PROCESSOR_REVISION': '1e05', 18:56:27 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 18:56:27 INFO - 'PROGRAMFILES': 'C:\\Program Files', 18:56:27 INFO - 'PROMPT': '$P$G', 18:56:27 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 18:56:27 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 18:56:27 INFO - 'PUBLIC': 'C:\\Users\\Public', 18:56:27 INFO - 'PWD': 'C:\\slave\\test', 18:56:27 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 18:56:27 INFO - 'SLAVEDIR': 'C:\\slave\\', 18:56:27 INFO - 'SYSTEMDRIVE': 'C:', 18:56:27 INFO - 'SYSTEMROOT': 'C:\\windows', 18:56:27 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:56:27 INFO - 'TEST1': 'testie', 18:56:27 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:56:27 INFO - 'USERDOMAIN': 'T-W732-IX-110', 18:56:27 INFO - 'USERNAME': 'cltbld', 18:56:27 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 18:56:27 INFO - 'WINDIR': 'C:\\windows', 18:56:27 INFO - 'WINDOWS_TRACING_FLAGS': '3', 18:56:27 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 18:56:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 18:56:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:27 INFO - Downloading/unpacking blobuploader==1.2.4 18:56:27 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 18:56:27 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 18:56:28 INFO - Downloading blobuploader-1.2.4.tar.gz 18:56:28 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 18:56:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:56:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:28 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests 18:56:29 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:56:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:29 INFO - Downloading docopt-0.6.1.tar.gz 18:56:29 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt 18:56:29 INFO - Installing collected packages: blobuploader, requests, docopt 18:56:29 INFO - Running setup.py install for blobuploader 18:56:29 INFO - Running setup.py install for requests 18:56:30 INFO - Running setup.py install for docopt 18:56:30 INFO - Successfully installed blobuploader requests docopt 18:56:30 INFO - Cleaning up... 18:56:31 INFO - Return code: 0 18:56:31 INFO - Installing None into virtualenv C:\slave\test\build/venv 18:56:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:31 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 0x0184B640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C6E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017C2B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018A0E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01FE5520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-110', 'USERDOMAIN': 'T-W732-IX-110', '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-110', '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 18:56:31 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 18:56:31 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 18:56:31 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 18:56:31 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 18:56:31 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 18:56:31 INFO - 'COMPUTERNAME': 'T-W732-IX-110', 18:56:31 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 18:56:31 INFO - 'CYGWINBASE': 'C:\\cygwin', 18:56:31 INFO - 'DCLOCATION': 'SCL3', 18:56:31 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 18:56:31 INFO - 'FP_NO_HOST_CHECK': 'NO', 18:56:31 INFO - 'HOMEDRIVE': 'C:', 18:56:31 INFO - 'HOMEPATH': '\\Users\\cltbld', 18:56:31 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 18:56:31 INFO - 'KTS_VERSION': '1.19c', 18:56:31 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 18:56:31 INFO - 'LOGONSERVER': '\\\\T-W732-IX-110', 18:56:31 INFO - 'MONDIR': 'C:\\Monitor_config\\', 18:56:31 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 18:56:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:31 INFO - 'MOZ_NO_REMOTE': '1', 18:56:31 INFO - 'NO_EM_RESTART': '1', 18:56:31 INFO - 'NUMBER_OF_PROCESSORS': '8', 18:56:31 INFO - 'OS': 'Windows_NT', 18:56:31 INFO - 'OURDRIVE': 'C:', 18:56:31 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 18:56:31 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 18:56:31 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 18:56:31 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 18:56:31 INFO - 'PROCESSOR_LEVEL': '6', 18:56:31 INFO - 'PROCESSOR_REVISION': '1e05', 18:56:31 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 18:56:31 INFO - 'PROGRAMFILES': 'C:\\Program Files', 18:56:31 INFO - 'PROMPT': '$P$G', 18:56:31 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 18:56:31 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 18:56:31 INFO - 'PUBLIC': 'C:\\Users\\Public', 18:56:31 INFO - 'PWD': 'C:\\slave\\test', 18:56:31 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 18:56:31 INFO - 'SLAVEDIR': 'C:\\slave\\', 18:56:31 INFO - 'SYSTEMDRIVE': 'C:', 18:56:31 INFO - 'SYSTEMROOT': 'C:\\windows', 18:56:31 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:56:31 INFO - 'TEST1': 'testie', 18:56:31 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:56:31 INFO - 'USERDOMAIN': 'T-W732-IX-110', 18:56:31 INFO - 'USERNAME': 'cltbld', 18:56:31 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 18:56:31 INFO - 'WINDIR': 'C:\\windows', 18:56:31 INFO - 'WINDOWS_TRACING_FLAGS': '3', 18:56:31 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 18:56:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 18:56:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:32 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 18:56:32 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pucwai-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 18:56:32 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 18:56:32 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xv0v1h-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 18:56:32 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 18:56:32 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-e0e6ym-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 18:56:32 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 18:56:32 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-frjbxp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 18:56:32 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 18:56:32 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-srmtg2-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 18:56:33 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 18:56:33 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vlzq5s-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 18:56:33 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 18:56:33 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-_nf0rz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 18:56:33 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 18:56:33 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bfj00j-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 18:56:33 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 18:56:33 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bpamu3-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 18:56:33 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 18:56:33 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wvvple-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 18:56:33 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 18:56:33 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ofrky4-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 18:56:33 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 18:56:33 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-aozlux-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 18:56:34 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 18:56:34 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-px2lkq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 18:56:34 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 18:56:34 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pf2cig-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 18:56:34 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 18:56:34 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ilfelh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 18:56:34 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 18:56:34 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-uchjef-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 18:56:34 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 18:56:34 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wepx8g-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 18:56:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:56:34 INFO - Running setup.py install for manifestparser 18:56:34 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts 18:56:34 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts 18:56:35 INFO - Running setup.py install for mozcrash 18:56:35 INFO - Running setup.py install for mozdebug 18:56:35 INFO - Running setup.py install for mozdevice 18:56:36 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts 18:56:36 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts 18:56:36 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts 18:56:36 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts 18:56:36 INFO - Running setup.py install for mozfile 18:56:37 INFO - Running setup.py install for mozhttpd 18:56:37 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts 18:56:37 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts 18:56:37 INFO - Running setup.py install for mozinfo 18:56:37 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts 18:56:37 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts 18:56:37 INFO - Running setup.py install for mozInstall 18:56:37 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts 18:56:37 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts 18:56:37 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts 18:56:37 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts 18:56:37 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts 18:56:37 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts 18:56:37 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts 18:56:37 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts 18:56:38 INFO - Running setup.py install for mozleak 18:56:38 INFO - Running setup.py install for mozlog 18:56:38 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts 18:56:38 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts 18:56:39 INFO - Running setup.py install for moznetwork 18:56:39 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts 18:56:39 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts 18:56:39 INFO - Running setup.py install for mozprocess 18:56:40 INFO - Running setup.py install for mozprofile 18:56:40 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts 18:56:40 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts 18:56:40 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts 18:56:40 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts 18:56:40 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts 18:56:40 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts 18:56:40 INFO - Running setup.py install for mozrunner 18:56:41 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts 18:56:41 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts 18:56:41 INFO - Running setup.py install for mozscreenshot 18:56:42 INFO - Running setup.py install for moztest 18:56:42 INFO - Running setup.py install for mozversion 18:56:42 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts 18:56:42 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts 18:56:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:56:42 INFO - Cleaning up... 18:56:42 INFO - Return code: 0 18:56:42 INFO - Installing None into virtualenv C:\slave\test\build/venv 18:56:42 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')]} 18:56:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:42 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')]} 18:56:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:42 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 0x0184B640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C6E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017C2B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018A0E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01FE5520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-110', 'USERDOMAIN': 'T-W732-IX-110', '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-110', '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 18:56:42 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 18:56:42 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 18:56:42 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 18:56:42 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 18:56:42 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 18:56:42 INFO - 'COMPUTERNAME': 'T-W732-IX-110', 18:56:42 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 18:56:42 INFO - 'CYGWINBASE': 'C:\\cygwin', 18:56:42 INFO - 'DCLOCATION': 'SCL3', 18:56:42 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 18:56:42 INFO - 'FP_NO_HOST_CHECK': 'NO', 18:56:42 INFO - 'HOMEDRIVE': 'C:', 18:56:42 INFO - 'HOMEPATH': '\\Users\\cltbld', 18:56:42 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 18:56:42 INFO - 'KTS_VERSION': '1.19c', 18:56:42 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 18:56:42 INFO - 'LOGONSERVER': '\\\\T-W732-IX-110', 18:56:42 INFO - 'MONDIR': 'C:\\Monitor_config\\', 18:56:42 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 18:56:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:42 INFO - 'MOZ_NO_REMOTE': '1', 18:56:42 INFO - 'NO_EM_RESTART': '1', 18:56:42 INFO - 'NUMBER_OF_PROCESSORS': '8', 18:56:42 INFO - 'OS': 'Windows_NT', 18:56:42 INFO - 'OURDRIVE': 'C:', 18:56:42 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;', 18:56:42 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 18:56:42 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 18:56:42 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 18:56:42 INFO - 'PROCESSOR_LEVEL': '6', 18:56:42 INFO - 'PROCESSOR_REVISION': '1e05', 18:56:42 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 18:56:42 INFO - 'PROGRAMFILES': 'C:\\Program Files', 18:56:42 INFO - 'PROMPT': '$P$G', 18:56:42 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 18:56:42 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 18:56:42 INFO - 'PUBLIC': 'C:\\Users\\Public', 18:56:42 INFO - 'PWD': 'C:\\slave\\test', 18:56:42 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 18:56:42 INFO - 'SLAVEDIR': 'C:\\slave\\', 18:56:42 INFO - 'SYSTEMDRIVE': 'C:', 18:56:42 INFO - 'SYSTEMROOT': 'C:\\windows', 18:56:42 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:56:42 INFO - 'TEST1': 'testie', 18:56:42 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:56:42 INFO - 'USERDOMAIN': 'T-W732-IX-110', 18:56:42 INFO - 'USERNAME': 'cltbld', 18:56:42 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 18:56:42 INFO - 'WINDIR': 'C:\\windows', 18:56:42 INFO - 'WINDOWS_TRACING_FLAGS': '3', 18:56:42 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 18:56:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 18:56:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 18:56:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-0x4kve-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 18:56:43 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)) 18:56:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 18:56:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cywgjp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 18:56:43 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)) 18:56:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 18:56:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gmutxo-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 18:56:43 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)) 18:56:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 18:56:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-groki6-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 18:56:43 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)) 18:56:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 18:56:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-0imnpx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 18:56:44 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)) 18:56:44 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 18:56:44 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-6jvnmk-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 18:56:44 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)) 18:56:44 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 18:56:44 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-k7bzmd-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 18:56:44 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)) 18:56:44 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 18:56:44 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-twefpd-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 18:56:44 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)) 18:56:44 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 18:56:44 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ue9qt5-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 18:56:44 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)) 18:56:44 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 18:56:44 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-138rna-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 18:56:44 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)) 18:56:44 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 18:56:44 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-sqpia_-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 18:56:44 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)) 18:56:44 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 18:56:44 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-9ompub-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 18:56:45 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)) 18:56:45 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 18:56:45 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-1s_h_t-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 18:56:45 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)) 18:56:45 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 18:56:45 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-6eooyq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 18:56:45 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)) 18:56:45 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 18:56:45 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-4ee9hu-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 18:56:45 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)) 18:56:45 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 18:56:45 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mwsv8r-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 18:56:45 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)) 18:56:45 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 18:56:45 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bzs5w7-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 18:56:45 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)) 18:56:45 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)) 18:56:45 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)) 18:56:45 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)) 18:56:45 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)) 18:56:45 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)) 18:56:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10)) 18:56:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:46 INFO - Downloading blessings-1.6.tar.gz 18:56:46 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings 18:56:47 INFO - Installing collected packages: blessings 18:56:47 INFO - Running setup.py install for blessings 18:56:47 INFO - Successfully installed blessings 18:56:47 INFO - Cleaning up... 18:56:50 INFO - Return code: 0 18:56:50 INFO - Done creating virtualenv C:\slave\test\build/venv. 18:56:50 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 18:56:50 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 18:56:50 INFO - Reading from file tmpfile_stdout 18:56:50 INFO - Using _rmtree_windows ... 18:56:50 INFO - Using _rmtree_windows ... 18:56:50 INFO - Current package versions: 18:56:50 INFO - blessings == 1.6 18:56:50 INFO - blobuploader == 1.2.4 18:56:50 INFO - docopt == 0.6.1 18:56:50 INFO - functools32 == 3.2.3-2 18:56:50 INFO - jsonschema == 2.5.1 18:56:50 INFO - manifestparser == 1.1 18:56:50 INFO - mozInstall == 1.12 18:56:50 INFO - mozcrash == 0.17 18:56:50 INFO - mozdebug == 0.1 18:56:50 INFO - mozdevice == 0.48 18:56:50 INFO - mozfile == 1.2 18:56:50 INFO - mozhttpd == 0.7 18:56:50 INFO - mozinfo == 0.9 18:56:50 INFO - mozleak == 0.1 18:56:50 INFO - mozlog == 3.4 18:56:50 INFO - moznetwork == 0.27 18:56:50 INFO - mozprocess == 0.23 18:56:50 INFO - mozprofile == 0.28 18:56:50 INFO - mozrunner == 6.12 18:56:50 INFO - mozscreenshot == 0.1 18:56:50 INFO - mozsystemmonitor == 0.3 18:56:50 INFO - moztest == 0.7 18:56:50 INFO - mozversion == 1.4 18:56:50 INFO - psutil == 3.1.1 18:56:50 INFO - requests == 1.2.3 18:56:50 INFO - Installing None into virtualenv C:\slave\test\build/venv 18:56: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')]} 18:56:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56: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')]} 18:56:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:50 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 0x0184B640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C6E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017C2B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018A0E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01FE5520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-110', 'USERDOMAIN': 'T-W732-IX-110', '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-110', '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 18:56:50 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 18:56:50 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 18:56:50 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 18:56:50 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 18:56:50 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 18:56:50 INFO - 'COMPUTERNAME': 'T-W732-IX-110', 18:56:50 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 18:56:50 INFO - 'CYGWINBASE': 'C:\\cygwin', 18:56:50 INFO - 'DCLOCATION': 'SCL3', 18:56:50 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 18:56:50 INFO - 'FP_NO_HOST_CHECK': 'NO', 18:56:50 INFO - 'HOMEDRIVE': 'C:', 18:56:50 INFO - 'HOMEPATH': '\\Users\\cltbld', 18:56:50 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 18:56:50 INFO - 'KTS_VERSION': '1.19c', 18:56:50 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 18:56:50 INFO - 'LOGONSERVER': '\\\\T-W732-IX-110', 18:56:50 INFO - 'MONDIR': 'C:\\Monitor_config\\', 18:56:50 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 18:56:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:50 INFO - 'MOZ_NO_REMOTE': '1', 18:56:50 INFO - 'NO_EM_RESTART': '1', 18:56:50 INFO - 'NUMBER_OF_PROCESSORS': '8', 18:56:50 INFO - 'OS': 'Windows_NT', 18:56:50 INFO - 'OURDRIVE': 'C:', 18:56:50 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 18:56:50 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 18:56:50 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 18:56:50 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 18:56:50 INFO - 'PROCESSOR_LEVEL': '6', 18:56:50 INFO - 'PROCESSOR_REVISION': '1e05', 18:56:50 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 18:56:50 INFO - 'PROGRAMFILES': 'C:\\Program Files', 18:56:50 INFO - 'PROMPT': '$P$G', 18:56:50 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 18:56:50 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 18:56:50 INFO - 'PUBLIC': 'C:\\Users\\Public', 18:56:50 INFO - 'PWD': 'C:\\slave\\test', 18:56:50 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 18:56:50 INFO - 'SLAVEDIR': 'C:\\slave\\', 18:56:50 INFO - 'SYSTEMDRIVE': 'C:', 18:56:50 INFO - 'SYSTEMROOT': 'C:\\windows', 18:56:50 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:56:50 INFO - 'TEST1': 'testie', 18:56:50 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:56:50 INFO - 'USERDOMAIN': 'T-W732-IX-110', 18:56:50 INFO - 'USERNAME': 'cltbld', 18:56:50 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 18:56:50 INFO - 'WINDIR': 'C:\\windows', 18:56:50 INFO - 'WINDOWS_TRACING_FLAGS': '3', 18:56:50 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 18:56:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 18:56:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:50 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)) 18:56:50 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)) 18:56:50 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)) 18:56:50 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)) 18:56:50 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)) 18:56:50 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)) 18:56:50 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)) 18:56:50 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)) 18:56:50 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)) 18:56:50 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)) 18:56:50 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)) 18:56:50 INFO - Cleaning up... 18:56:50 INFO - Return code: 0 18:56:50 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv 18:56: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')]} 18:56:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56: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')]} 18:56:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:50 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 0x0184B640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C6E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017C2B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018A0E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01FE5520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-110', 'USERDOMAIN': 'T-W732-IX-110', '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-110', '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 18:56:50 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 18:56:50 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 18:56:50 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 18:56:50 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 18:56:50 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 18:56:50 INFO - 'COMPUTERNAME': 'T-W732-IX-110', 18:56:50 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 18:56:50 INFO - 'CYGWINBASE': 'C:\\cygwin', 18:56:50 INFO - 'DCLOCATION': 'SCL3', 18:56:50 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 18:56:50 INFO - 'FP_NO_HOST_CHECK': 'NO', 18:56:50 INFO - 'HOMEDRIVE': 'C:', 18:56:50 INFO - 'HOMEPATH': '\\Users\\cltbld', 18:56:50 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 18:56:50 INFO - 'KTS_VERSION': '1.19c', 18:56:50 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 18:56:50 INFO - 'LOGONSERVER': '\\\\T-W732-IX-110', 18:56:50 INFO - 'MONDIR': 'C:\\Monitor_config\\', 18:56:50 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 18:56:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:50 INFO - 'MOZ_NO_REMOTE': '1', 18:56:50 INFO - 'NO_EM_RESTART': '1', 18:56:50 INFO - 'NUMBER_OF_PROCESSORS': '8', 18:56:50 INFO - 'OS': 'Windows_NT', 18:56:50 INFO - 'OURDRIVE': 'C:', 18:56:50 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 18:56:50 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 18:56:50 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 18:56:50 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 18:56:50 INFO - 'PROCESSOR_LEVEL': '6', 18:56:50 INFO - 'PROCESSOR_REVISION': '1e05', 18:56:50 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 18:56:50 INFO - 'PROGRAMFILES': 'C:\\Program Files', 18:56:50 INFO - 'PROMPT': '$P$G', 18:56:50 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 18:56:50 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 18:56:50 INFO - 'PUBLIC': 'C:\\Users\\Public', 18:56:50 INFO - 'PWD': 'C:\\slave\\test', 18:56:50 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 18:56:50 INFO - 'SLAVEDIR': 'C:\\slave\\', 18:56:50 INFO - 'SYSTEMDRIVE': 'C:', 18:56:50 INFO - 'SYSTEMROOT': 'C:\\windows', 18:56:50 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:56:50 INFO - 'TEST1': 'testie', 18:56:50 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:56:50 INFO - 'USERDOMAIN': 'T-W732-IX-110', 18:56:50 INFO - 'USERNAME': 'cltbld', 18:56:50 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 18:56:50 INFO - 'WINDIR': 'C:\\windows', 18:56:50 INFO - 'WINDOWS_TRACING_FLAGS': '3', 18:56:50 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 18:56:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 18:56:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:51 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages 18:56:51 INFO - Cleaning up... 18:56:51 INFO - Return code: 0 18:56:51 INFO - Running post-action listener: _resource_record_post_action 18:56:51 INFO - Running post-action listener: _start_resource_monitoring 18:56:51 INFO - Starting resource monitoring. 18:56:51 INFO - [mozharness: 2016-10-21 01:56:51.362000Z] Finished create-virtualenv step (success) 18:56:51 INFO - [mozharness: 2016-10-21 01:56:51.362000Z] Running install step. 18:56:51 INFO - Running pre-action listener: _resource_record_pre_action 18:56:51 INFO - Running main action method: install 18:56:51 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 18:56:51 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 18:56:51 INFO - Reading from file tmpfile_stdout 18:56:51 INFO - Using _rmtree_windows ... 18:56:51 INFO - Using _rmtree_windows ... 18:56:51 INFO - Detecting whether we're running mozinstall >=1.0... 18:56:51 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h'] 18:56:51 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h 18:56:51 INFO - Reading from file tmpfile_stdout 18:56:51 INFO - Output received: 18:56:51 INFO - Usage: mozinstall-script.py [options] installer 18:56:51 INFO - Options: 18:56:51 INFO - -h, --help show this help message and exit 18:56:51 INFO - -d DEST, --destination=DEST 18:56:51 INFO - Directory to install application into. [default: 18:56:51 INFO - "C:\slave\test"] 18:56:51 INFO - --app=APP Application being installed. [default: firefox] 18:56:51 INFO - Using _rmtree_windows ... 18:56:51 INFO - Using _rmtree_windows ... 18:56:51 INFO - mkdir: C:\slave\test\build\application 18:56:51 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'] 18:56:51 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 18:56:54 INFO - Reading from file tmpfile_stdout 18:56:54 INFO - Output received: 18:56:54 INFO - C:\slave\test\build\application\firefox\firefox.exe 18:56:54 INFO - Using _rmtree_windows ... 18:56:54 INFO - Using _rmtree_windows ... 18:56:54 INFO - Running post-action listener: _resource_record_post_action 18:56:54 INFO - [mozharness: 2016-10-21 01:56:54.019000Z] Finished install step (success) 18:56:54 INFO - [mozharness: 2016-10-21 01:56:54.019000Z] Running run-tests step. 18:56:54 INFO - Running pre-action listener: _resource_record_pre_action 18:56:54 INFO - Running main action method: run_tests 18:56:54 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version'] 18:56:54 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version 18:56:54 INFO - Python 2.7.3 18:56:54 INFO - Return code: 0 18:56:54 INFO - grabbing minidump binary from tooltool 18:56:54 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')]} 18:56:54 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 0x018A0E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01FE5520>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1 18:56:54 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 18:56:54 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 18:56:54 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 18:56:54 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 18:56:55 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmpgingag 18:56:55 INFO - INFO - File integrity verified, renaming tmpgingag to win32-minidump_stackwalk.exe 18:56:55 INFO - Return code: 0 18:56:55 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755 18:56:55 INFO - mkdir: C:\slave\test\build\blobber_upload_dir 18:56:55 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir 18:56:55 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe 18:56:55 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir 18:56:55 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/1477002622/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-110', '--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 18:56:55 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/1477002622/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-110 --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 18:56:55 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 18:56:55 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 18:56:55 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 18:56:55 INFO - 'COMPUTERNAME': 'T-W732-IX-110', 18:56:55 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 18:56:55 INFO - 'CYGWINBASE': 'C:\\cygwin', 18:56:55 INFO - 'DCLOCATION': 'SCL3', 18:56:55 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 18:56:55 INFO - 'FP_NO_HOST_CHECK': 'NO', 18:56:55 INFO - 'HOMEDRIVE': 'C:', 18:56:55 INFO - 'HOMEPATH': '\\Users\\cltbld', 18:56:55 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 18:56:55 INFO - 'KTS_VERSION': '1.19c', 18:56:55 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 18:56:55 INFO - 'LOGONSERVER': '\\\\T-W732-IX-110', 18:56:55 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir', 18:56:55 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe', 18:56:55 INFO - 'MONDIR': 'C:\\Monitor_config\\', 18:56:55 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 18:56:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:55 INFO - 'MOZ_NO_REMOTE': '1', 18:56:55 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir', 18:56:55 INFO - 'NO_EM_RESTART': '1', 18:56:55 INFO - 'NUMBER_OF_PROCESSORS': '8', 18:56:55 INFO - 'OS': 'Windows_NT', 18:56:55 INFO - 'OURDRIVE': 'C:', 18:56:55 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;', 18:56:55 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 18:56:55 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 18:56:55 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 18:56:56 INFO - 'PROCESSOR_LEVEL': '6', 18:56:56 INFO - 'PROCESSOR_REVISION': '1e05', 18:56:56 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 18:56:56 INFO - 'PROGRAMFILES': 'C:\\Program Files', 18:56:56 INFO - 'PROMPT': '$P$G', 18:56:56 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 18:56:56 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 18:56:56 INFO - 'PUBLIC': 'C:\\Users\\Public', 18:56:56 INFO - 'PWD': 'C:\\slave\\test', 18:56:56 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos', 18:56:56 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 18:56:56 INFO - 'SLAVEDIR': 'C:\\slave\\', 18:56:56 INFO - 'SYSTEMDRIVE': 'C:', 18:56:56 INFO - 'SYSTEMROOT': 'C:\\windows', 18:56:56 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:56:56 INFO - 'TEST1': 'testie', 18:56:56 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:56:56 INFO - 'USERDOMAIN': 'T-W732-IX-110', 18:56:56 INFO - 'USERNAME': 'cltbld', 18:56:56 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 18:56:56 INFO - 'WINDIR': 'C:\\windows', 18:56:56 INFO - 'WINDOWS_TRACING_FLAGS': '3', 18:56:56 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 18:56:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 18:56:56 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/1477002622/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-110', '--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 18:56:56 INFO - mozversion application_buildid: 20161020153022 18:56:56 INFO - mozversion application_changeset: ba6fc133264d8e7aa8adf43d9d88f48b6dc2ffac 18:56:56 INFO - mozversion application_display_name: Nightly 18:56:56 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:56:56 INFO - mozversion application_name: Firefox 18:56:56 INFO - mozversion application_remotingname: firefox 18:56:56 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 18:56:56 INFO - mozversion application_vendor: Mozilla 18:56:56 INFO - mozversion application_version: 52.0a1 18:56:56 INFO - mozversion platform_buildid: 20161020153022 18:56:56 INFO - mozversion platform_changeset: ba6fc133264d8e7aa8adf43d9d88f48b6dc2ffac 18:56:56 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 18:56:56 INFO - mozversion platform_version: 52.0a1 18:56:56 INFO - using testdate: 1477015016 18:56:56 INFO - actual date: 1477015016 18:56:56 INFO - starting webserver on 'localhost:49653' 18:56:56 INFO - SUITE-START | Running 2 tests 18:56:56 INFO - TEST-START | dromaeo_css 18:56:56 INFO - operating with platform_type : w7_ 18:56:56 INFO - Initialising browser for dromaeo_css test... 18:56:57 INFO - TEST-INFO | started process 4088 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmphdnvix\profile http://localhost:49653/getInfo.html) 18:57:03 INFO - PROCESS | 4088 | __metrics Screen width/height:1600/1200 18:57:03 INFO - PROCESS | 4088 | colorDepth:24 18:57:03 INFO - PROCESS | 4088 | Browser inner width/height: 1010/674 18:57:03 INFO - PROCESS | 4088 | __metrics 18:57:04 INFO - PROCESS | 4088 | 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" 18:57:04 INFO - PROCESS | 4088 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:57:05 INFO - TEST-INFO | 4088: exit 0 18:57:05 INFO - Browser initialized. 18:57:05 INFO - Running cycle 1/1 for dromaeo_css test... 18:57:05 INFO - TEST-INFO | started process 3524 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmphdnvix\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:57:06 INFO - PROCESS | 3524 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0 18:57:17 INFO - PROCESS | 3524 | RSS: Main: 123064320 18:57:17 INFO - PROCESS | 3524 | 18:58:08 INFO - PROCESS | 3524 | 1477015088253 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] 19:00:18 INFO - PROCESS | 3524 | Cycle 1(1): loaded http://localhost:49653/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49653/tests/dromaeo/cssquery-ext.html) 19:00:19 INFO - PROCESS | 3524 | RSS: Main: 206045184 19:00:19 INFO - PROCESS | 3524 | 19:03:22 INFO - PROCESS | 3524 | Cycle 1(1): loaded http://localhost:49653/tests/dromaeo/cssquery-ext.html (next: http://localhost:49653/tests/dromaeo/cssquery-jquery.html) 19:03:22 INFO - PROCESS | 3524 | RSS: Main: 197365760 19:03:22 INFO - PROCESS | 3524 | 19:06:24 INFO - PROCESS | 3524 | Cycle 1(1): loaded http://localhost:49653/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49653/tests/dromaeo/cssquery-mootools.html) 19:06:25 INFO - PROCESS | 3524 | RSS: Main: 145543168 19:06:25 INFO - PROCESS | 3524 | 19:09:27 INFO - PROCESS | 3524 | Cycle 1(1): loaded http://localhost:49653/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49653/tests/dromaeo/cssquery-prototype.html) 19:09:27 INFO - PROCESS | 3524 | RSS: Main: 144588800 19:09:27 INFO - PROCESS | 3524 | 19:12:30 INFO - PROCESS | 3524 | Cycle 1(1): loaded http://localhost:49653/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49653/tests/dromaeo/cssquery-yui.html) 19:12:31 INFO - PROCESS | 3524 | RSS: Main: 132608000 19:12:31 INFO - PROCESS | 3524 | 19:15:34 INFO - PROCESS | 3524 | Cycle 1(1): loaded http://localhost:49653/tests/dromaeo/cssquery-yui.html (next: http://localhost:49653/tests/dromaeo/cssquery-dojo.html) 19:15:34 INFO - PROCESS | 3524 | RSS: Main: 182464512 19:15:34 INFO - PROCESS | 3524 | 19:15:34 INFO - PROCESS | 3524 | __start_tp_report 19:15:34 INFO - PROCESS | 3524 | _x_x_mozilla_page_load 19:15:34 INFO - PROCESS | 3524 | _x_x_mozilla_page_load_details 19:15:34 INFO - PROCESS | 3524 | |i|pagename|runs| 19:15:34 INFO - PROCESS | 3524 | |0;dojo.html;2555.859427731475;2559.731228221037;2573.7683608475245;2590.7150213476525;2709.254954887406;4376.19040477511;4395.912081758363;4484.125595508877;4507.188706032913;4690.788914498848;4285.550017248191;4430.667699922511;4535.977320113403;4556.248219043862;4609.723416595007;3782.2624588205313;3789.5263092113487;3792.469054332382;3814.1608846053823;4009.8396064157473;3798.2973149967206;3798.582155962842;3798.6011468795655;3798.9240215195678;3940.4089386591954;3876.573576906538;3907.6483116519507;3956.525216973967;4027.3958906164016;4060.066184777503;4091.304478238677;4107.938380924288;4141.047559061402;4165.9999999999845;4192.517860446057;3816.274907767543;3867.9613203867884;3889.2610404023144;3896.922061558753;3978.8209530571194;4503.752293623849;4506.391637128979;4583.702059366131;4653.464851542082;4655.930161047587;4641.837535686234;4778.474367819572;4828.251620998751;4853.951460485385;4912.042151780404;4746.406699162605;4796.784144713496;4859.319695242669;4954.355933728628;4965.652404331699;4527.909441811178;4555.293929440975;4559.74921379324;4577.473590537064;4597.218472859622;4911.582515486152;4921.138800709861;5000.974995125001;5020.297158397827;5025.070361983045;4606.976965115153;4714.57568818808;4729.101470720552;4824.396950381202;4830.468648448667;4570.880134367035;4637.489876113625;4788.353572267769;4903.926441103386;4941.962187940501;4809.807607695653;4823.083614113378;4914.189158788831;4919.606431485473;4924;4602.05657840142;4856.028794241166;4864.489228630943;4868.123737727243;4926.926096108561;3113.688631136868;3231.3052693670975;3253.674632536775;3254.1213872254357;3300.273939733255;2867.3261783480866;3025.8638361273797;3076.32320889404;3079.799813012147;3081.9983505360847;4473.686841921034;4513.232750432368;4546.818127274872;4558.635309175258;4635.258358662665;4505.301678239878;4599.885002874955;4675.812967481263;4842.273658951185;4985.177445721415;4446;4515.322701594787;4536.637069034469;4542.11428771393;4568.200564901129;4028.5971402860064;4033.6773058155864;4123.60825721556;4130.173965206971;4131.215069136914;4924.901501969869;5020.949790502122;5024.648274620741;5073.492650734897;5116.053530096944;4566.862994110182;4761.785719642694;4792.616590672668;4943.555080042814;4946.950530494649;4782.282657601388;5075.3148324975655;5175.663581867167;5184.662996905189;5185.688858668341;4585.816567337402;4590.2426099693175;4691.483936766885;4900.852974410774;4903.092927808224;4651.069786042873;4718.0799744050955;4796.736179510161;4900.534449227318;4994.076095922266;4567.703099298535;4686.695364801277;4771.212749896228;4791.281307803857;4814.229723244264;4575.764543573283;4644.814207431665;4781.976090119527;4789.3294938709305;4800.73595952226;4450.888727781702;4518.457785065813;4612.423447069116;4632.444106707217;4640.303954406866;4544.636429085599;4560.794764235551;4738.928916066193;4781.856544303677;4804.231322988305;4593.28804035375;4664.416947881515;4697.6711630185555;4795.520447955177;4806.086843499724 19:15:34 INFO - PROCESS | 3524 | |1;ext.html;535.386981905718;569.3651578489985;574.9137629355597;586.3842964886868;601.8284788835183;29309.706902930964;39287.410688839656;40379.59620403795;40608;41968;40533.797331013375;40545;40615.984600385;40945.59054409455;40960.79519602405;6880.105586273778;7729.149793522722;8047.074586422548;8059.07320658126;8074.757757267262;6792.796216113525;9804.01959804021;10021.749456263615;10023.599056037787;10270.794584108313;10049.346792458504;10072.848907266396;10113.190944724442;10121.696349109501;10138.59445622178;6817.659117044128;9971;10019;10083.243756718275;10135.23985701068;6410.551261411703;6564.310747371505;6938;9263.675771348038;9888.357256778358;37199.44200836989;37476.25047499062;37536.81231593852;37553.43669844927;37599.812000940095;2909.185428080141;2947.2484516448385;3048.9542656860167;3258.820764857932;3473.7915725056323;28156.43687126246;29270;29536.852315738284;29990.85004574963;30010.099697009126;2879.4960881845595;2922.941541169165;2934.373735506731;3029.803062800911;3033.726964573177;311721;379799;401111;401831;404780.99999999703;50568.98862022739;50887;51488.742556287725;51619.483805161464;51820;51471;52384;53331;53514.197287040726;53725;12356.505739770488;12404.75190496185;12502.874741273328;12569.497220111092;12663.810042849365;8630.223279904838;8856.070112638208;9520.23838092951;9592;9617.374870633386;16333.26500307489;16732.498025059023;16760.916195418944;17083.914580427267;17121.486355409357;6697.187181383831;6712.93287067123;6717.899231511531;6728.495362847708;6774.966125169342;778.9727359542388;845.247729520727;851.4508142248216;871.6600525794945;883.6774577279247;26141;27111.72888271092;27121.86439067792;27344;27567.31081722973;878.292974155798;913.7030465098869;929.4098247612815;987.53585814667;1016.9373005209574;13680;13971.58085257444;14111.364988575539;14128.293585320693;14411.85588144126;474152;503009;520102;548430;766820;48618.75690621595;49084.50915490871;49124;49147.75426122847;49203;1010.3786171504619;1026.0047753678919;1068.8183008888352;1137.5961533655764;1146.5528443906717;27207.59188612133;27232;28471;28541.429171416872;28629.141125766262;41742.79128604338;42378.78810606051;42404;42410;42469.36295955625;386620;391951;395762;405319;407091;21676.67484987758;22796.088156473554;23006.42483937915;23053.538929221657;23326.533469330177;41096.79451602723;41256.381154282106;41270.793646031576;41746.791266043474;42126.578734212264;34951.30097397987;35070.82464587763;35379.82310088433;36211;36838.44742328813;35372;35523.64476355203;35689.82155089208;36559;37133.628663714095;32695.34609307753;33955.15112122217;34157;34158.65841341555;34278.48582271218;31014;31257;31654.093615489637;32838.83580582082;32937.341253175284;24639.38401539976;25764.742352576985;25773.742262577885;26021.6096758545;26331.736682632927 19:15:34 INFO - PROCESS | 3524 | |2;jquery.html;270.7400895140665;290.3612053482339;294.84225939122575;297.69040198193875;298.817721190074;12739.363031848405;13658.590242292734;16893.493195204148;17070.743938840897;17870.28518859244;16662.250198741054;16743.748843767324;17082.914585427054;17292.394766183184;17332.566685832862;4723.976380118094;5004.774785134678;5199.4800519948085;5203.869903252411;5216.426193118754;5095.515925987025;5104.132297509414;5119.411267704206;5128.692278463285;5222.190560463116;5066.898662026757;5074.4925507449325;5078.085944530001;5083.237514372855;5083.288339632454;4809.398825146856;4875.609951203895;4892.192788189945;4950.851474455772;5081.618878584121;4496.865094047183;4504.6396288296855;4528.913060865401;4530.546945305476;4533.274676051816;21041.684374734392;21666.350009499583;21812.018459169376;21920.89039554808;22003;1892.706630472279;1919.808019198083;2004.3485867093252;2059.3204242600054;2116.0477784996815;12380.938095309555;14052;14060.296985150804;14367.425302987867;14452.855471445362;1594.4180374163464;1687.6202854357794;1713.6144367517334;1719.7420386942058;1835.5869929265941;83893.58053209695;86222.56888715517;87512;87707.56146219291;87872.12127878703;8608.741737747878;8703.912960870437;8773.649054037895;8881.289496840238;9014.954925225464;9605.47169905662;9773.560189791335;10561.841572376421;11432.428378581038;11446.656600302003;4382.452193475816;4628.652851036186;4682.063587282557;6044.848878777978;6544.312847151075;6343;6405.743770249231;6444.871102577922;6464.5798023128345;6502.60984340941;3353.832308384571;3508.0002199373566;3569.250457403922;3628.1111127773866;3688.8524459021337;1466.4647403697575;1527.2287494815048;1529.7628867525552;1544.7914531538324;1548.5431797619729;782.1240210963643;817.3093735793245;818.8730746734266;822.0135836995629;842.9842040341374;17367.3053077878;17653.646927061385;17770.733438998424;17835.019073950796;17844.286228550973;793.8571057209758;799.3605115907251;828.1676914700687;871.6077765005773;928.326962951868;9511.762205944906;9668.323217374716;9867.210623150131;10037.598496060222;10179.185665146919;330681;335973;345313;354085;356745;92001;92853.5357323209;92902;92905.53547232355;95287.52356238312;904.344350346007;909.9727008189898;925.9351845370894;954.5036580977853;955.0926619711302;15411;15695.607609809846;15822.28799703993;16086.43697470583;16113.400166004876;39784.80107599444;41854.58145418507;42166.57833421627;42307;42605.65668888241;58757.4124258769;58946;59341.70329148327;59794;60865.39134608597;6290.119383286252;6320.273168585672;6344;6390.4248617624635;6763.188417389944;9212.447253164832;13165.53920612774;13331.466741330627;13357.198568085945;13378.86621133815;10275.177985761271;10380.32527885685;10863;11047.969745223058;11356.716082098013;10105.9494702526;10763.677089687322;10777.13782897382;11226.270292430965;11395.601153959571;10794.514246858753;10965.451727413756;10985.780284394426;11351.432428378714;11690.532378705097;6935.375816176568;7020.333068358498;7071.752488662872;7114.430845532448;7170.533915295489;9403.764905877408;10731.141508679442;10825.729356766144;10894;11050.33697978124 19:15:34 INFO - PROCESS | 3524 | |3;mootools.html;1121.506537123666;1154.665147107339;1199.9880001199988;1276.5532063777675;1530.3648985670948;6001.429864162908;6609.074729537869;6718.6304753238555;6754.932450675498;6868.244493105767;6684.16447944007;6799.42204912583;6907.965460172704;6910.654467276629;6915.204751453584;1832.459424469778;1871.7005279155337;1872.7190921361837;1873.7657792775904;1880.1539307311764;1797.541626885149;1831.5238038110053;1854.8330650241476;1856.8143185681458;1862.8602854785877;1758.2088060372819;1834.990825045873;1835.3667984545377;1839.273486972645;1855.8329750322468;1701.174930158872;1755.7454169145467;1766.8409843114182;1787.347618119377;1789.5883946692204;1769.1596491666485;1769.9203485873606;1773.8226177382287;1778.5909240874541;1780.5370603643016;6616.437602803768;6682.83292917676;6712.496562757764;6791.762288319935;6793.456523478159;3894.3768996960634;3960.089179488734;3988.501437320335;4028.3554631259153;4041.333180025292;2547.223096955428;2574.1891304239293;2576.1756238003845;2578.755018273261;2583.186296316654;758.1773775453639;782.5249208627426;792.9563873986929;808.3694718119876;813.4631143445298;8598.570071496462;8677.609507572175;8845.734627961172;8850.291976641853;8851.911480885174;3360.5799275090612;3433.828308584561;3495.3009398120475;3496.877609283663;3522.119470132467;7557.4331925105835;7813.335866451302;7831.6475758591005;7843.37253019757;7844.254795794391;3913.25648126855;4050.270951228807;4059.2490389277486;4062.289099407592;4063.5326937401987;3336.199312165063;3437.570303712036;3752.080740218664;3786;3793.108619474421;2982.1948074019892;3081.306705991134;3111.315510587667;3118.656947735747;3132.98433507831;967.8693376394237;973.1679414100984;984.2322988069318;984.9903848555288;991.108002797488;767.9001729775093;779.0534500581821;783.0525064671697;793.3098204562068;811.0713233347784;4303.195442160019;4389.714668546598;4560.475545312332;4602;4671.598520443853;767.7850201943464;806.5765473126655;810.4326971120122;813.8657121574993;813.9796505087302;3114.8286844223785;3184.933047429091;3191.8987949157504;3207.6631953644533;3211.245357341023;8060.959695201486;8071.4753541019645;8110.270075693216;8187.672493100328;8234.958825205835;8140;8178.345732341399;8184;8195.508269503849;8205.302549283258;6228.944448332039;6376.29860715321;6380.457661098766;6386.137871387396;6386.265579458329;4506.256467682796;4648.3027545867035;4687.835925742583;4730.692504987165;4755.928661070156;7238.203797582157;7618.8095297618;7627.5042122261875;7643.923560764321;7652.65563049653;7802.726904558313;7809.297163255334;7829.569373684502;7830.4518683691595;7830.608469576385;5792.218050563289;5848.619839710405;5988.940110599013;6003.729832157476;6097.969510152599;5990.281166259902;6167.167432396748;6383.712732926937;6408.166938297992;6644.767433139817;5942.346341902301;6075.605085669417;6224.533160012927;6251.6561589113035;6255.78104766331;5744.109663002241;5899.085641725539;5930.822075337746;5958.463738263577;6044.879102418015;5173.611979101507;5396.406395296593;5397.676139431646;5405.0270951227585;5408.242846001642;3961.8811435656976;4023;4067.491563554556;4080.224757296104;4085.8365665373053;3844.8077596119524;3940.3301438754906;3955.4660120883314;3967.9206415870945;4039.3738970459626 19:15:34 INFO - PROCESS | 3524 | |4;prototype.html;219.45246609708778;220.2906640617213;225.12763043207576;226.56838722234139;228.39930981518603;5050.8484745457645;5074.2388641703765;5096.388433387994;5098.056859480998;5115.872103197413;4821.3491178690865;4898;4923.18767403378;4931.531504507075;5015.874603134924;3544.663256990582;3589.7128229741625;3597.37046016946;3633.6911362534215;3645.9453108203397;3654.872079477219;3656.3966945453967;3656.579493358258;3657.2319812839337;3658.1037645776687;3539.50446937429;3600.5859326177565;3619.7104231661406;3621.3481573316794;3624.7281453890932;3438.896833094999;3483.9825800871085;3502.859885604573;3515.578130624329;3522.5420695309704;2900.941981160386;3073.5082386818226;3079.045495896279;3096.489079301935;3102.2709663229125;4855.417349918032;4864.294677271811;4882.853514394574;4887.902241955141;4895.0454661341055;4199.265128602482;4201.390798334255;4203.432536607549;4209.936850947238;4216.894577635553;4996.550310472075;5051.924221136685;5136.306598609177;5154.819581314636;5196.480351964811;3432.6567343265474;3444.3800115979116;3445.844936977842;3449.844756985942;3450.723942084627;5411.594130440193;5423.29497165366;5426.131818908955;5439.5376393006645;5447.45525447456;3324.484704870749;3348.777696140891;3495.702865256482;3505.9824700876334;3510.3330367230146;3544.80503572306;3545.62770909056;3547.52108465359;3556;3557.4663800430144;3816.2749077675153;4046.089629833264;4062.370332598452;4067.2678917795083;4209.621134097884;4995.625328100335;5066.949330506722;5161.535461808456;5214.97392513035;5226.817061402909;1163.139276935062;1175.753091850721;1176.688177632928;1181.1023622047305;1186.027457484854;678.9660516974131;722.4437183368776;728.6757392960081;729.7227053719553;731.7182884589471;4464.8391418231495;4467.810772721408;4475.373447717322;4803.543663351976;4922.606191504671;5307.893842123136;5330.333708286465;5427.294451721251;5439.972800135974;5451.672899626035;4914.287428322873;4921.926171107436;4946.650341087954;4947.282644016669;4950.133726597831;2843.2039029071893;2848.544232922764;2851.187411587729;2869.9856500717365;2870.669872964636;5344.625876188629;5649.830505084854;5672.957735215319;5674.801381951612;5699.233237696328;5462.672239665632;5579.302587176603;5583.134614134734;5591.133374326986;5607.8598035048635;5597.272354593958;5708.942910570841;5713.657180569096;5716.742746576414;5718.856228754265;5121.719570107473;5246.475352464723;5417.891642167214;5430.592705547021;5470.507654311131;5277.736113194402;5347.0375332440535;5357.330333708286;5385.919211211756;5392.352917649907;5520.806771762969;5641.294838145232;5671.461211184931;5680.233168522197;5695;3303.827141364781;3367.410703126992;3369.3261347730063;3373.3590617782543;3388.1360253135294;4986.401631804207;5411.242426060276;5436.619436639556;5440;5460.563154947674;5060.291559181791;5094.974525127499;5209.166533354645;5254.00173966945;5266.710330931683;5158.819441319686;5161.509656582619;5201.895962080813;5218.504242096995;5259.395169555397;5047.41954675217;5172.301739265151;5172.818951336685;5185.22221666753;5197.766100525561;4990.925136122888;5255.737213139404;5255.921161182662;5267.71027593486;5286.841394758163;4964.826231081895;5054.342935418372;5145.742712864267;5194.1429664105;5221.321228240457 19:15:34 INFO - PROCESS | 3524 | |5;yui.html;643.2602507116815;674.399784192069;680.4862328941648;685.732564299923;705.5484489926448;13798.931005344972;15156.858277778338;15242.618934526643;15246.23768811559;15625.218739063059;13967.720645587082;14105.294735263222;14114.929425352859;14121.78817317741;14127.576172714835;529.0503546134678;541.4098632490585;622.0886401421905;640.6860638287229;647.6081970407882;663.4161937495022;672.4149989509134;673.9898901516481;676.2189670930084;676.5805200775526;696.0325148044222;696.2828286864537;696.8327601375684;697.219114591658;698.183125742882;550.2901257377978;564.3538148819579;669.7588868007514;680.45223595006;698.9755358562452;472.60537451228265;491.14050411779385;498.8229178641603;502.75867583560097;503.09191908604953;13342.13276137048;13368.197908125543;13827.585172444742;13833.584992450242;13848.307584620728;12575.559855405108;12912.612621621367;12982.09125361225;13357;13450.663733406645;12483.875161248363;12765.617031488977;13129.737405251937;13239.404226809816;13291;3842.193139440693;4082;4113.115680128757;4113.91772164558;4165.500139983191;66539;67645;68807.65596171987;77075.61462192654;77245.61377193134;9762.463064531377;9764.365316254563;9813.11681948628;10344.32761870476;10452.947735261276;17085.743713844302;17610.735838962424;17771.20029598645;18090.095495225185;18131.818681813278;6392.488600912009;6851.554648947803;7089.716411343592;7225.2774722528375;7267.127944646676;3644.599094099647;3692.1877187018813;3692.2430901665093;3818.560865500503;3824.8087595620664;2707.472042951645;2793.7485626293733;2876.2377969838008;2880;2881.697421770684;487.1791032110834;489.1244672037085;491.76395330241684;492.85953503251403;497.14987371594253;594.8512871782054;635.1045026512595;649.2630863969334;669.5647828911149;670.9932900671029;8754.212120909022;8806.458869697777;9213.953930230306;9399.295750095887;9636.325457217927;552.4392741367515;604.8941435248814;637.1144109687538;639.9008153736179;679.9252082270946;4115.160539227923;4332.826686932551;4491.46102467706;4529.909401812011;4538.455385353713;75087;113595;114688;115873.42063289798;116187;67797.66101169462;86427;92902;97759;98548.50725746325;68344.97482537766;84901.15098848933;85179.14820851837;87847.56076219706;88673;8270;8937.955310223408;9115.498647574446;9176.174144327042;9213.493257870881;49501.75249123731;51696.741516292175;52350.21474677807;52413.47586524086;53041.469585305196;65361.67319163564;71303.64348178226;74437;75141;76200.23799761932;11055.391953442317;11164.888351116384;11180.888191117985;11190.608328708457;11631;12840.293783841978;13421;13467.797983030066;13638.74019668866;14007.579772606838;11996.880031199926;12115.636530904087;12686.873131268938;12858.292793896426;12910.677233068873;12169.69575760613;12379.133460657667;12397.00823934065;12442.652578896279;12623.810642840548;11456.94271528637;11582.536698532302;11773.587924422603;11891.144553746437;12083.939580302394;10412.583496660049;10845.349279043283;10993.285436446606;11083;11086.168537359892;10886.455677216265;11223.438828058728;11604.883951160718;11615.52319620975;11832.408379581158 19:15:34 INFO - PROCESS | 3524 | __end_tp_report 19:15:34 INFO - PROCESS | 3524 | __start_cc_report 19:15:34 INFO - PROCESS | 3524 | _x_x_mozilla_cycle_collect,1391 19:15:34 INFO - PROCESS | 3524 | __end_cc_report 19:15:34 INFO - PROCESS | 3524 | __startTimestamp1477016134301__endTimestamp 19:15:34 INFO - PROCESS | 3524 | 19:15:34 INFO - PROCESS | 3524 | ------- Summary: start ------- 19:15:34 INFO - PROCESS | 3524 | Number of tests: 6 19:15:34 INFO - PROCESS | 3524 | 19:15:34 INFO - PROCESS | 3524 | [#0] dojo.html Cycles:165 Average:4431.54 Median:4599.89 stddev:587.45 (12.8%) stddev-sans-first:570.52 19:15:34 INFO - PROCESS | 3524 | Values: 2555.9 2559.7 2573.8 2590.7 2709.3 4376.2 4395.9 4484.1 4507.2 4690.8 4285.6 4430.7 4536.0 4556.2 4609.7 3782.3 3789.5 3792.5 3814.2 4009.8 3798.3 3798.6 3798.6 3798.9 3940.4 3876.6 3907.6 3956.5 4027.4 4060.1 4091.3 4107.9 4141.0 4166.0 4192.5 3816.3 3868.0 3889.3 3896.9 3978.8 4503.8 4506.4 4583.7 4653.5 4655.9 4641.8 4778.5 4828.3 4854.0 4912.0 4746.4 4796.8 4859.3 4954.4 4965.7 4527.9 4555.3 4559.7 4577.5 4597.2 4911.6 4921.1 5001.0 5020.3 5025.1 4607.0 4714.6 4729.1 4824.4 4830.5 4570.9 4637.5 4788.4 4903.9 4942.0 4809.8 4823.1 4914.2 4919.6 4924.0 4602.1 4856.0 4864.5 4868.1 4926.9 3113.7 3231.3 3253.7 3254.1 3300.3 2867.3 3025.9 3076.3 3079.8 3082.0 4473.7 4513.2 4546.8 4558.6 4635.3 4505.3 4599.9 4675.8 4842.3 4985.2 4446.0 4515.3 4536.6 4542.1 4568.2 4028.6 4033.7 4123.6 4130.2 4131.2 4924.9 5020.9 5024.6 5073.5 5116.1 4566.9 4761.8 4792.6 4943.6 4947.0 4782.3 5075.3 5175.7 5184.7 5185.7 4585.8 4590.2 4691.5 4900.9 4903.1 4651.1 4718.1 4796.7 4900.5 4994.1 4567.7 4686.7 4771.2 4791.3 4814.2 4575.8 4644.8 4782.0 4789.3 4800.7 4450.9 4518.5 4612.4 4632.4 4640.3 4544.6 4560.8 4738.9 4781.9 4804.2 4593.3 4664.4 4697.7 4795.5 4806.1 19:15:34 INFO - PROCESS | 3524 | 19:15:34 INFO - PROCESS | 3524 | [#1] ext.html Cycles:180 Average:57694.04 Median:37506.53 stddev:122412.84 (326.4%) stddev-sans-first:122681.02 19:15:34 INFO - PROCESS | 3524 | Values: 535.4 569.4 574.9 586.4 601.8 29309.7 39287.4 40379.6 40608.0 41968.0 40533.8 40545.0 40616.0 40945.6 40960.8 6880.1 7729.1 8047.1 8059.1 8074.8 6792.8 9804.0 10021.7 10023.6 10270.8 10049.3 10072.8 10113.2 10121.7 10138.6 6817.7 9971.0 10019.0 10083.2 10135.2 6410.6 6564.3 6938.0 9263.7 9888.4 37199.4 37476.3 37536.8 37553.4 37599.8 2909.2 2947.2 3049.0 3258.8 3473.8 28156.4 29270.0 29536.9 29990.9 30010.1 2879.5 2922.9 2934.4 3029.8 3033.7 311721.0 379799.0 401111.0 401831.0 404781.0 50569.0 50887.0 51488.7 51619.5 51820.0 51471.0 52384.0 53331.0 53514.2 53725.0 12356.5 12404.8 12502.9 12569.5 12663.8 8630.2 8856.1 9520.2 9592.0 9617.4 16333.3 16732.5 16760.9 17083.9 17121.5 6697.2 6712.9 6717.9 6728.5 6775.0 779.0 845.2 851.5 871.7 883.7 26141.0 27111.7 27121.9 27344.0 27567.3 878.3 913.7 929.4 987.5 1016.9 13680.0 13971.6 14111.4 14128.3 14411.9 474152.0 503009.0 520102.0 548430.0 766820.0 48618.8 49084.5 49124.0 49147.8 49203.0 1010.4 1026.0 1068.8 1137.6 1146.6 27207.6 27232.0 28471.0 28541.4 28629.1 41742.8 42378.8 42404.0 42410.0 42469.4 386620.0 391951.0 395762.0 405319.0 407091.0 21676.7 22796.1 23006.4 23053.5 23326.5 41096.8 41256.4 41270.8 41746.8 42126.6 34951.3 35070.8 35379.8 36211.0 36838.4 35372.0 35523.6 35689.8 36559.0 37133.6 32695.3 33955.2 34157.0 34158.7 34278.5 31014.0 31257.0 31654.1 32838.8 32937.3 24639.4 25764.7 25773.7 26021.6 26331.7 19:15:34 INFO - PROCESS | 3524 | 19:15:34 INFO - PROCESS | 3524 | [#2] jquery.html Cycles:180 Average:24279.25 Median:42236.79 stddev:58394.50 (138.3%) stddev-sans-first:58530.49 19:15:34 INFO - PROCESS | 3524 | Values: 270.7 290.4 294.8 297.7 298.8 12739.4 13658.6 16893.5 17070.7 17870.3 16662.3 16743.7 17082.9 17292.4 17332.6 4724.0 5004.8 5199.5 5203.9 5216.4 5095.5 5104.1 5119.4 5128.7 5222.2 5066.9 5074.5 5078.1 5083.2 5083.3 4809.4 4875.6 4892.2 4950.9 5081.6 4496.9 4504.6 4528.9 4530.5 4533.3 21041.7 21666.4 21812.0 21920.9 22003.0 1892.7 1919.8 2004.3 2059.3 2116.0 12380.9 14052.0 14060.3 14367.4 14452.9 1594.4 1687.6 1713.6 1719.7 1835.6 83893.6 86222.6 87512.0 87707.6 87872.1 8608.7 8703.9 8773.6 8881.3 9015.0 9605.5 9773.6 10561.8 11432.4 11446.7 4382.5 4628.7 4682.1 6044.8 6544.3 6343.0 6405.7 6444.9 6464.6 6502.6 3353.8 3508.0 3569.3 3628.1 3688.9 1466.5 1527.2 1529.8 1544.8 1548.5 782.1 817.3 818.9 822.0 843.0 17367.3 17653.6 17770.7 17835.0 17844.3 793.9 799.4 828.2 871.6 928.3 9511.8 9668.3 9867.2 10037.6 10179.2 330681.0 335973.0 345313.0 354085.0 356745.0 92001.0 92853.5 92902.0 92905.5 95287.5 904.3 910.0 925.9 954.5 955.1 15411.0 15695.6 15822.3 16086.4 16113.4 39784.8 41854.6 42166.6 42307.0 42605.7 58757.4 58946.0 59341.7 59794.0 60865.4 6290.1 6320.3 6344.0 6390.4 6763.2 9212.4 13165.5 13331.5 13357.2 13378.9 10275.2 10380.3 10863.0 11048.0 11356.7 10105.9 10763.7 10777.1 11226.3 11395.6 10794.5 10965.5 10985.8 11351.4 11690.5 6935.4 7020.3 7071.8 7114.4 7170.5 9403.8 10731.1 10825.7 10894.0 11050.3 19:15:34 INFO - PROCESS | 3524 | 19:15:34 INFO - PROCESS | 3524 | [#3] mootools.html Cycles:180 Average:4422.43 Median:5768.16 stddev:2455.37 (42.6%) stddev-sans-first:2449.73 19:15:34 INFO - PROCESS | 3524 | Values: 1121.5 1154.7 1200.0 1276.6 1530.4 6001.4 6609.1 6718.6 6754.9 6868.2 6684.2 6799.4 6908.0 6910.7 6915.2 1832.5 1871.7 1872.7 1873.8 1880.2 1797.5 1831.5 1854.8 1856.8 1862.9 1758.2 1835.0 1835.4 1839.3 1855.8 1701.2 1755.7 1766.8 1787.3 1789.6 1769.2 1769.9 1773.8 1778.6 1780.5 6616.4 6682.8 6712.5 6791.8 6793.5 3894.4 3960.1 3988.5 4028.4 4041.3 2547.2 2574.2 2576.2 2578.8 2583.2 758.2 782.5 793.0 808.4 813.5 8598.6 8677.6 8845.7 8850.3 8851.9 3360.6 3433.8 3495.3 3496.9 3522.1 7557.4 7813.3 7831.6 7843.4 7844.3 3913.3 4050.3 4059.2 4062.3 4063.5 3336.2 3437.6 3752.1 3786.0 3793.1 2982.2 3081.3 3111.3 3118.7 3133.0 967.9 973.2 984.2 985.0 991.1 767.9 779.1 783.1 793.3 811.1 4303.2 4389.7 4560.5 4602.0 4671.6 767.8 806.6 810.4 813.9 814.0 3114.8 3184.9 3191.9 3207.7 3211.2 8061.0 8071.5 8110.3 8187.7 8235.0 8140.0 8178.3 8184.0 8195.5 8205.3 6228.9 6376.3 6380.5 6386.1 6386.3 4506.3 4648.3 4687.8 4730.7 4755.9 7238.2 7618.8 7627.5 7643.9 7652.7 7802.7 7809.3 7829.6 7830.5 7830.6 5792.2 5848.6 5988.9 6003.7 6098.0 5990.3 6167.2 6383.7 6408.2 6644.8 5942.3 6075.6 6224.5 6251.7 6255.8 5744.1 5899.1 5930.8 5958.5 6044.9 5173.6 5396.4 5397.7 5405.0 5408.2 3961.9 4023.0 4067.5 4080.2 4085.8 3844.8 3940.3 3955.5 3967.9 4039.4 19:15:34 INFO - PROCESS | 3524 | 19:15:34 INFO - PROCESS | 3524 | [#4] prototype.html Cycles:180 Average:4286.84 Median:4988.66 stddev:1376.90 (27.6%) stddev-sans-first:1346.50 19:15:34 INFO - PROCESS | 3524 | Values: 219.5 220.3 225.1 226.6 228.4 5050.8 5074.2 5096.4 5098.1 5115.9 4821.3 4898.0 4923.2 4931.5 5015.9 3544.7 3589.7 3597.4 3633.7 3645.9 3654.9 3656.4 3656.6 3657.2 3658.1 3539.5 3600.6 3619.7 3621.3 3624.7 3438.9 3484.0 3502.9 3515.6 3522.5 2900.9 3073.5 3079.0 3096.5 3102.3 4855.4 4864.3 4882.9 4887.9 4895.0 4199.3 4201.4 4203.4 4209.9 4216.9 4996.6 5051.9 5136.3 5154.8 5196.5 3432.7 3444.4 3445.8 3449.8 3450.7 5411.6 5423.3 5426.1 5439.5 5447.5 3324.5 3348.8 3495.7 3506.0 3510.3 3544.8 3545.6 3547.5 3556.0 3557.5 3816.3 4046.1 4062.4 4067.3 4209.6 4995.6 5066.9 5161.5 5215.0 5226.8 1163.1 1175.8 1176.7 1181.1 1186.0 679.0 722.4 728.7 729.7 731.7 4464.8 4467.8 4475.4 4803.5 4922.6 5307.9 5330.3 5427.3 5440.0 5451.7 4914.3 4921.9 4946.7 4947.3 4950.1 2843.2 2848.5 2851.2 2870.0 2870.7 5344.6 5649.8 5673.0 5674.8 5699.2 5462.7 5579.3 5583.1 5591.1 5607.9 5597.3 5708.9 5713.7 5716.7 5718.9 5121.7 5246.5 5417.9 5430.6 5470.5 5277.7 5347.0 5357.3 5385.9 5392.4 5520.8 5641.3 5671.5 5680.2 5695.0 3303.8 3367.4 3369.3 3373.4 3388.1 4986.4 5411.2 5436.6 5440.0 5460.6 5060.3 5095.0 5209.2 5254.0 5266.7 5158.8 5161.5 5201.9 5218.5 5259.4 5047.4 5172.3 5172.8 5185.2 5197.8 4990.9 5255.7 5255.9 5267.7 5286.8 4964.8 5054.3 5145.7 5194.1 5221.3 19:15:34 INFO - PROCESS | 3524 | 19:15:34 INFO - PROCESS | 3524 | [#5] yui.html Cycles:180 Average:19455.50 Median:2505.53 stddev:28345.04 (1131.3%) stddev-sans-first:28389.36 19:15:34 INFO - PROCESS | 3524 | Values: 643.3 674.4 680.5 685.7 705.5 13798.9 15156.9 15242.6 15246.2 15625.2 13967.7 14105.3 14114.9 14121.8 14127.6 529.1 541.4 622.1 640.7 647.6 663.4 672.4 674.0 676.2 676.6 696.0 696.3 696.8 697.2 698.2 550.3 564.4 669.8 680.5 699.0 472.6 491.1 498.8 502.8 503.1 13342.1 13368.2 13827.6 13833.6 13848.3 12575.6 12912.6 12982.1 13357.0 13450.7 12483.9 12765.6 13129.7 13239.4 13291.0 3842.2 4082.0 4113.1 4113.9 4165.5 66539.0 67645.0 68807.7 77075.6 77245.6 9762.5 9764.4 9813.1 10344.3 10452.9 17085.7 17610.7 17771.2 18090.1 18131.8 6392.5 6851.6 7089.7 7225.3 7267.1 3644.6 3692.2 3692.2 3818.6 3824.8 2707.5 2793.7 2876.2 2880.0 2881.7 487.2 489.1 491.8 492.9 497.1 594.9 635.1 649.3 669.6 671.0 8754.2 8806.5 9214.0 9399.3 9636.3 552.4 604.9 637.1 639.9 679.9 4115.2 4332.8 4491.5 4529.9 4538.5 75087.0 113595.0 114688.0 115873.4 116187.0 67797.7 86427.0 92902.0 97759.0 98548.5 68345.0 84901.2 85179.1 87847.6 88673.0 8270.0 8938.0 9115.5 9176.2 9213.5 49501.8 51696.7 52350.2 52413.5 53041.5 65361.7 71303.6 74437.0 75141.0 76200.2 11055.4 11164.9 11180.9 11190.6 11631.0 12840.3 13421.0 13467.8 13638.7 14007.6 11996.9 12115.6 12686.9 12858.3 12910.7 12169.7 12379.1 12397.0 12442.7 12623.8 11456.9 11582.5 11773.6 11891.1 12083.9 10412.6 10845.3 10993.3 11083.0 11086.2 10886.5 11223.4 11604.9 11615.5 11832.4 19:15:34 INFO - PROCESS | 3524 | -------- Summary: end -------- 19:15:34 INFO - PROCESS | 3524 | 19:15:35 INFO - TEST-INFO | 3524: exit 0 19:15:35 INFO - TEST-OK | dromaeo_css | took 1118713ms 19:15:35 INFO - TEST-START | kraken 19:15:35 INFO - operating with platform_type : w7_ 19:15:35 INFO - Initialising browser for kraken test... 19:15:35 INFO - TEST-INFO | started process 3000 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpkuucfm\profile http://localhost:49653/getInfo.html) 19:15:40 INFO - PROCESS | 3000 | __metrics Screen width/height:1600/1200 19:15:40 INFO - PROCESS | 3000 | colorDepth:24 19:15:40 INFO - PROCESS | 3000 | Browser inner width/height: 1010/674 19:15:40 INFO - PROCESS | 3000 | __metrics 19:15:40 INFO - PROCESS | 3000 | 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" 19:15:40 INFO - PROCESS | 3000 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:15:40 INFO - PROCESS | 3000 | console.error: 19:15:40 INFO - PROCESS | 3000 | Message: Error: Connection closed before committing the transaction. 19:15:40 INFO - PROCESS | 3000 | Stack: 19:15:40 INFO - PROCESS | 3000 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:937:23 19:15:40 INFO - PROCESS | 3000 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 19:15:40 INFO - PROCESS | 3000 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 19:15:40 INFO - PROCESS | 3000 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 19:15:40 INFO - PROCESS | 3000 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 19:15:40 INFO - PROCESS | 3000 | TaskImpl_run@resource://gre/modules/Task.jsm:327:15 19:15:40 INFO - PROCESS | 3000 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9 19:15:40 INFO - PROCESS | 3000 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 19:15:40 INFO - PROCESS | 3000 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5 19:15:40 INFO - PROCESS | 3000 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 19:15:40 INFO - PROCESS | 3000 | Barrier/this.client.addBlocker/promise13.2% 19:16:38 INFO - TinderboxPrint: I/O read bytes / time
74,069,504 / 1,396,600 19:16:38 INFO - TinderboxPrint: I/O write bytes / time
462,168,064 / 3,625,600 19:16:38 INFO - TinderboxPrint: CPU idle
8,241.9 (86.8%) 19:16:38 INFO - TinderboxPrint: CPU user
1,209.7 (12.7%) 19:16:38 INFO - install - Wall time: 3s; CPU: 11.0%; Read bytes: 11333120; Write bytes: 33639424; Read time: 247160; Write time: 123430 19:16:38 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 62736384; Write bytes: 392549376; Read time: 1149440; Write time: 3474500 19:16:39 INFO - Running post-run listener: _upload_blobber_files 19:16:39 INFO - Blob upload gear active. 19:16:39 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir. 19:16:39 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 19:16:39 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'] 19:16:39 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 19:16:40 INFO - (blobuploader) - INFO - Open directory for files ... 19:16:40 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log ... 19:16:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:16:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:16:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:16:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:16:41 INFO - (blobuploader) - INFO - Done attempting. 19:16:41 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log ... 19:16:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:16:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:16:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:16:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:16:42 INFO - (blobuploader) - INFO - Done attempting. 19:16:42 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ... 19:16:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:16:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:16:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:16:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:16:51 INFO - (blobuploader) - INFO - Done attempting. 19:16:51 INFO - (blobuploader) - INFO - Iteration through files over. 19:16:51 INFO - Return code: 0 19:16:51 INFO - rmtree: C:\slave\test\build\uploaded_files.json 19:16:51 INFO - Using _rmtree_windows ... 19:16:51 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1 19:16:51 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/216042f192618ab85b88cb9bd76b63a15d2df911b802da7223decd7aa44bd06c56e8f1f4dbb988696d03ed89f33c9a38d51a9604b4e563abb8d59b0e1ad586ed", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/497031ae923518cd38e5ac8f87dd9fda6926b0de4af26357cee0ed6e612b7449fc9c4e8b6e6b09e7a51a858b9af9e7a43234fa594a53a72d51d42049ef55da4c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/111d4078e3d659852da7a9d431f50d09c6ad4100b5553c492885848c8d6eee941b23e22518928c09c57b02f937a31bf70361599ba0446d863c0b016a838dc0fd"} 19:16:51 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files 19:16:51 INFO - Writing to file C:\slave\test\properties\blobber_files 19:16:51 INFO - Contents: 19:16:51 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/216042f192618ab85b88cb9bd76b63a15d2df911b802da7223decd7aa44bd06c56e8f1f4dbb988696d03ed89f33c9a38d51a9604b4e563abb8d59b0e1ad586ed", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/497031ae923518cd38e5ac8f87dd9fda6926b0de4af26357cee0ed6e612b7449fc9c4e8b6e6b09e7a51a858b9af9e7a43234fa594a53a72d51d42049ef55da4c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/111d4078e3d659852da7a9d431f50d09c6ad4100b5553c492885848c8d6eee941b23e22518928c09c57b02f937a31bf70361599ba0446d863c0b016a838dc0fd"} 19:16:51 INFO - Running post-run listener: copy_logs_to_upload_dir 19:16:51 INFO - Copying logs to upload dir... 19:16:51 INFO - mkdir: C:\slave\test\build\upload\logs 19:16:51 INFO - Copying logs to upload dir... 19:16:51 INFO - Using _rmtree_windows ... 19:16:51 INFO - Using _rmtree_windows ... 19:16:51 INFO - Using _rmtree_windows ... 19:16:51 INFO - Using _rmtree_windows ... 19:16:51 INFO - Using _rmtree_windows ... 19:16:51 INFO - Using _rmtree_windows ... 19:16:51 INFO - Using _rmtree_windows ... program finished with exit code 0 elapsedTime=1484.345000 ========= master_lag: 0.44 ========= ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 44 secs) (at 2016-10-20 19:16:51.829277) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-20 19:16:51.831742) ========= '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-110 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-110 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-110 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/216042f192618ab85b88cb9bd76b63a15d2df911b802da7223decd7aa44bd06c56e8f1f4dbb988696d03ed89f33c9a38d51a9604b4e563abb8d59b0e1ad586ed", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/497031ae923518cd38e5ac8f87dd9fda6926b0de4af26357cee0ed6e612b7449fc9c4e8b6e6b09e7a51a858b9af9e7a43234fa594a53a72d51d42049ef55da4c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/111d4078e3d659852da7a9d431f50d09c6ad4100b5553c492885848c8d6eee941b23e22518928c09c57b02f937a31bf70361599ba0446d863c0b016a838dc0fd"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477002622/firefox-52.0a1.en-US.win32.zip program finished with exit code 0 elapsedTime=0.101000 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1477002622/firefox-52.0a1.en-US.win32.zip' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/216042f192618ab85b88cb9bd76b63a15d2df911b802da7223decd7aa44bd06c56e8f1f4dbb988696d03ed89f33c9a38d51a9604b4e563abb8d59b0e1ad586ed", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/497031ae923518cd38e5ac8f87dd9fda6926b0de4af26357cee0ed6e612b7449fc9c4e8b6e6b09e7a51a858b9af9e7a43234fa594a53a72d51d42049ef55da4c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/111d4078e3d659852da7a9d431f50d09c6ad4100b5553c492885848c8d6eee941b23e22518928c09c57b02f937a31bf70361599ba0446d863c0b016a838dc0fd"}' ========= master_lag: 0.35 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-20 19:16:52.282071) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 19:16:52.282400) ========= '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-110 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-110 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-110 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-20 19:16:52.394395) ========= ========= Started reboot slave lost (results: 0, elapsed: 1 secs) (at 2016-10-20 19:16:52.394719) ========= ========= Finished reboot slave lost (results: 0, elapsed: 1 secs) (at 2016-10-20 19:16:53.476713) ========= ========= Total master_lag: 8.85 =========