builder: mozilla-central_win7_ix_test-dromaeojs-pgo slave: t-w732-ix-031 starttime: 1477944820.26 results: success (0) buildid: 20161031103102 builduid: 39cf5ce76b384393910f01d549b751c1 revision: 8c9eed5227f8681cf08d2ee8fb3bfd5d743e4096 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-31 13:13:40.257360) ========= master: http://buildbot-master110.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-31 13:13:40.257816) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-31 13:13:40.258097) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-31 13:13:40.430925) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 13:13:40.431610) ========= '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-031 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-031 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-031 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 --13:13:42-- 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% 7.55 MB/s 13:13:42 (7.55 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.952000 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 13:13:41.424081) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 14 secs) (at 2016-10-31 13:13:41.424417) ========= '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-031 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-031 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-031 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=14.001000 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 14 secs) (at 2016-10-31 13:13:55.476054) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-31 13:13:55.476477) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo mozilla-central --rev 8c9eed5227f8681cf08d2ee8fb3bfd5d743e4096 --destination scripts --debug' in dir C:\slave\test\. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 8c9eed5227f8681cf08d2ee8fb3bfd5d743e4096 --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-031 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-031 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-031 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-31 13:13:57,141 truncating revision to first 12 chars 2016-10-31 13:13:57,141 Setting DEBUG logging. 2016-10-31 13:13:57,141 attempt 1/10 2016-10-31 13:13:57,141 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8c9eed5227f8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-31 13:13:58,414 unpacking tar archive at: mozilla-central-8c9eed5227f8/testing/mozharness/ 2016-10-31 13:13:58,543 skipping symlink on windows: configs/single_locale/linux32.py program finished with exit code 0 elapsedTime=2.268000 ========= master_lag: 0.20 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-31 13:13:57.940950) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-31 13:13:57.941255) ========= script_repo_revision: 8c9eed5227f8681cf08d2ee8fb3bfd5d743e4096 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-31 13:13:57.941659) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-31 13:13:57.941938) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-31 13:13:57.955390) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 51 secs) (at 2016-10-31 13:13:57.955785) ========= 'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Firefox' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Firefox' 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', 'Firefox', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-031 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-031 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-031 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 13:13:59 INFO - MultiFileLogger online at 20161031 13:13:59 in C:\slave\test 13:13:59 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 13:13:59 INFO - Dumping config to C:\slave\test\logs\localconfig.json. 13:13:59 INFO - {'append_to_log': False, 13:13:59 INFO - 'base_work_dir': 'C:\\slave\\test', 13:13:59 INFO - 'blob_upload_branch': 'Firefox', 13:13:59 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt', 13:13:59 INFO - 'branch': 'Firefox', 13:13:59 INFO - 'buildbot_json_path': 'buildprops.json', 13:13:59 INFO - 'config_files': ('talos/windows_config.py',), 13:13:59 INFO - 'default_actions': ('clobber', 13:13:59 INFO - 'read-buildbot-config', 13:13:59 INFO - 'download-and-extract', 13:13:59 INFO - 'populate-webroot', 13:13:59 INFO - 'create-virtualenv', 13:13:59 INFO - 'install', 13:13:59 INFO - 'run-tests'), 13:13:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:13:59 INFO - 'download_minidump_stackwalk': True, 13:13:59 INFO - 'download_symbols': 'ondemand', 13:13:59 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python', 13:13:59 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'), 13:13:59 INFO - 'hg': 'c:/mozilla-build/hg/hg', 13:13:59 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python', 13:13:59 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'), 13:13:59 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 13:13:59 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 13:13:59 INFO - 'C:/mozilla-build/tooltool.py'), 13:13:59 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 13:13:59 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 13:13:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:13:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:13:59 INFO - 'installer_path': 'installer.exe', 13:13:59 INFO - 'log_level': 'info', 13:13:59 INFO - 'log_name': 'talos', 13:13:59 INFO - 'log_to_console': True, 13:13:59 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 13:13:59 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 13:13:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 13:13:59 INFO - 'opt_config_files': (), 13:13:59 INFO - 'pip_index': False, 13:13:59 INFO - 'sps_profile': False, 13:13:59 INFO - 'sps_profile_interval': 0, 13:13:59 INFO - 'suite': 'dromaeojs', 13:13:59 INFO - 'system_bits': '32', 13:13:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:13:59 INFO - 'title': 'T-W732-IX-031', 13:13:59 INFO - 'use_talos_json': True, 13:13:59 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 13:13:59 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv', 13:13:59 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 13:13:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:13:59 INFO - 'work_dir': 'build'} 13:13:59 INFO - [mozharness: 2016-10-31 20:13:59.720000Z] Running clobber step. 13:13:59 INFO - Running pre-action listener: _resource_record_pre_action 13:13:59 INFO - Running main action method: clobber 13:13:59 INFO - rmtree: C:\slave\test\build 13:13:59 INFO - Using _rmtree_windows ... 13:13:59 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1 13:17:08 INFO - Running post-action listener: _resource_record_post_action 13:17:08 INFO - [mozharness: 2016-10-31 20:17:08.957000Z] Finished clobber step (success) 13:17:08 INFO - [mozharness: 2016-10-31 20:17:08.957000Z] Running read-buildbot-config step. 13:17:08 INFO - Running pre-action listener: _resource_record_pre_action 13:17:08 INFO - Running main action method: read_buildbot_config 13:17:08 INFO - Using buildbot properties: 13:17:08 INFO - { 13:17:08 INFO - "project": "", 13:17:08 INFO - "product": "firefox", 13:17:08 INFO - "script_repo_revision": "production", 13:17:08 INFO - "builddir": "mozilla-central_win7_ix_test-dromaeojs", 13:17:08 INFO - "repository": "", 13:17:08 INFO - "buildername": "Windows 7 32-bit mozilla-central pgo talos dromaeojs", 13:17:08 INFO - "buildid": "20161031103102", 13:17:08 INFO - "pgo_build": "True", 13:17:08 INFO - "basedir": "C:\\slave\\test", 13:17:08 INFO - "buildnumber": 65, 13:17:08 INFO - "slavename": "t-w732-ix-031", 13:17:08 INFO - "master": "http://buildbot-master110.bb.releng.scl3.mozilla.com:8201/", 13:17:08 INFO - "platform": "win7_ix", 13:17:08 INFO - "scheduler": "tests-mozilla-central-win32-pgo-talos", 13:17:08 INFO - "branch": "mozilla-central", 13:17:08 INFO - "repo_path": "mozilla-central", 13:17:08 INFO - "revision": "8c9eed5227f8681cf08d2ee8fb3bfd5d743e4096", 13:17:08 INFO - "stage_platform": "win32-pgo", 13:17:08 INFO - "builduid": "39cf5ce76b384393910f01d549b751c1", 13:17:08 INFO - "slavebuilddir": "test" 13:17:08 INFO - } 13:17:08 INFO - Found installer url https://queue.taskcluster.net/v1/task/IGvUpc-MTVW6MYT8SS5gxw/artifacts/public/build/firefox-52.0a1.en-US.win32.zip. 13:17:08 INFO - Running post-action listener: _resource_record_post_action 13:17:08 INFO - [mozharness: 2016-10-31 20:17:08.960000Z] Finished read-buildbot-config step (success) 13:17:08 INFO - [mozharness: 2016-10-31 20:17:08.961000Z] Running download-and-extract step. 13:17:08 INFO - Running pre-action listener: _resource_record_pre_action 13:17:08 INFO - Running main action method: download_and_extract 13:17:08 INFO - mkdir: C:\slave\test\build\tests 13:17:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:17:08 INFO - trying https://queue.taskcluster.net/v1/task/IGvUpc-MTVW6MYT8SS5gxw/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json 13:17:08 INFO - Downloading https://queue.taskcluster.net/v1/task/IGvUpc-MTVW6MYT8SS5gxw/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json to C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json 13:17:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IGvUpc-MTVW6MYT8SS5gxw/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json', 'file_name': 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.test_packages.json'}, attempt #1 13:17:10 INFO - Downloaded 1218 bytes. 13:17:10 INFO - Reading from file C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json 13:17:10 INFO - Using the following test package requirements: 13:17:10 INFO - {u'common': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 13:17:10 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 13:17:10 INFO - u'firefox-52.0a1.en-US.win32.cppunittest.tests.zip'], 13:17:10 INFO - u'gtest': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 13:17:10 INFO - u'jittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 13:17:10 INFO - u'jsshell-win32.zip'], 13:17:10 INFO - u'mochitest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 13:17:10 INFO - u'firefox-52.0a1.en-US.win32.mochitest.tests.zip'], 13:17:10 INFO - u'mozbase': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 13:17:10 INFO - u'reftest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 13:17:10 INFO - u'firefox-52.0a1.en-US.win32.reftest.tests.zip'], 13:17:10 INFO - u'talos': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 13:17:10 INFO - u'firefox-52.0a1.en-US.win32.talos.tests.zip'], 13:17:10 INFO - u'web-platform': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 13:17:10 INFO - u'firefox-52.0a1.en-US.win32.web-platform.tests.zip'], 13:17:10 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 13:17:10 INFO - u'firefox-52.0a1.en-US.win32.xpcshell.tests.zip']} 13:17:10 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip'] for test suite category: common 13:17:10 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/IGvUpc-MTVW6MYT8SS5gxw/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip 13:17:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IGvUpc-MTVW6MYT8SS5gxw/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1 13:17:10 INFO - Fetch https://queue.taskcluster.net/v1/task/IGvUpc-MTVW6MYT8SS5gxw/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory 13:17:10 INFO - Http code: 200 13:17:10 INFO - via: 1.1 e1eb7447ab46f530f6009f1d93917c54.cloudfront.net (CloudFront) 13:17:10 INFO - x-amz-cf-id: aZXUUJk5S0CIZEJ3frcs0AFZpJ7J2MzcYcpIIpIwiCEwMx-8wGOVGA== 13:17:10 INFO - x-amz-version-id: uDSMmkXwnKe7cj_GJRdMz..djYHvMd3h 13:17:11 INFO - Expected file size: 17162355 13:17:11 INFO - Obtained file size: 17162355 13:17:37 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 13:17:37 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/IGvUpc-MTVW6MYT8SS5gxw/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip 13:17:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IGvUpc-MTVW6MYT8SS5gxw/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1 13:17:37 INFO - Fetch https://queue.taskcluster.net/v1/task/IGvUpc-MTVW6MYT8SS5gxw/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory 13:17:37 INFO - Http code: 200 13:17:37 INFO - via: 1.1 0cf1d7257c633ae75623c5e75bf3805e.cloudfront.net (CloudFront) 13:17:37 INFO - x-amz-cf-id: Pu6HrkN5Ydf2OK3t0OTAwMrR0pQonRaripUHBixLkP-luM1Tia6oMg== 13:17:37 INFO - x-amz-version-id: uDSMmkXwnKe7cj_GJRdMz..djYHvMd3h 13:17:38 INFO - Expected file size: 17162355 13:17:38 INFO - Obtained file size: 17162355 13:17:49 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/IGvUpc-MTVW6MYT8SS5gxw/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip 13:17:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IGvUpc-MTVW6MYT8SS5gxw/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip'}, attempt #1 13:17:49 INFO - Fetch https://queue.taskcluster.net/v1/task/IGvUpc-MTVW6MYT8SS5gxw/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip into memory 13:17:49 INFO - Http code: 200 13:17:49 INFO - via: 1.1 c0740de9f16cf32ffedfd2f9806b8483.cloudfront.net (CloudFront) 13:17:49 INFO - x-amz-cf-id: 5XUDrgX0CItbKmLKKJIb7Kigkbz_jjDJLNiQvMDaTFinH7TKNhzyGQ== 13:17:49 INFO - x-amz-version-id: WQwr1pz2puLfeiNAONMHAgq6QEMJ4ADg 13:17:50 INFO - Expected file size: 14409038 13:17:50 INFO - Obtained file size: 14409038 13:17: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')]} 13:17:52 INFO - trying https://queue.taskcluster.net/v1/task/IGvUpc-MTVW6MYT8SS5gxw/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 13:17:52 INFO - Downloading https://queue.taskcluster.net/v1/task/IGvUpc-MTVW6MYT8SS5gxw/artifacts/public/build/firefox-52.0a1.en-US.win32.zip to C:\slave\test\build\firefox-52.0a1.en-US.win32.zip 13:17:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IGvUpc-MTVW6MYT8SS5gxw/artifacts/public/build/firefox-52.0a1.en-US.win32.zip', 'file_name': 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.zip'}, attempt #1 13:17:54 INFO - Downloaded 54322379 bytes. 13:17:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IGvUpc-MTVW6MYT8SS5gxw/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 13:17:54 INFO - mkdir: C:\slave\test\properties 13:17:54 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url 13:17:54 INFO - Writing to file C:\slave\test\properties\build_url 13:17:54 INFO - Contents: 13:17:54 INFO - build_url:https://queue.taskcluster.net/v1/task/IGvUpc-MTVW6MYT8SS5gxw/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 13:17:54 INFO - Running post-action listener: _resource_record_post_action 13:17:54 INFO - Running post-action listener: set_extra_try_arguments 13:17:54 INFO - [mozharness: 2016-10-31 20:17:54.757000Z] Finished download-and-extract step (success) 13:17:54 INFO - [mozharness: 2016-10-31 20:17:54.757000Z] Running populate-webroot step. 13:17:54 INFO - Running pre-action listener: _resource_record_pre_action 13:17:54 INFO - Running main action method: populate_webroot 13:17:54 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 13:17:54 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 13:17:54 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 13:17:54 INFO - u'--tppagecycles', 13:17:54 INFO - u'7'], 13:17:54 INFO - u'tests': [u'tsvgm']}}, 13:17:54 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'], 13:17:54 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 13:17:54 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']}, 13:17:54 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'], 13:17:54 INFO - u'tests': [u'dromaeo_css', u'kraken']}, 13:17:54 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']}, 13:17:54 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:17:54 INFO - u'talos_options': [u'--disable-e10s'], 13:17:54 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 13:17:54 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:17:54 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 13:17:54 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:17:54 INFO - u'talos_options': [u'--disable-e10s'], 13:17:54 INFO - u'tests': [u'damp', u'tps']}, 13:17:54 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:17:54 INFO - u'tests': [u'damp', u'tps']}, 13:17:54 INFO - u'g3': {u'talos_options': [u'--disable-e10s'], 13:17:54 INFO - u'tests': [u'dromaeo_dom']}, 13:17:54 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 13:17:54 INFO - u'g4': {u'talos_options': [u'--disable-e10s'], 13:17:54 INFO - u'tests': [u'basic_compositor_video']}, 13:17:54 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']}, 13:17:54 INFO - u'other': {u'talos_options': [u'--disable-e10s'], 13:17:54 INFO - u'tests': [u'a11yr', 13:17:54 INFO - u'ts_paint', 13:17:54 INFO - u'tpaint', 13:17:54 INFO - u'sessionrestore', 13:17:54 INFO - u'sessionrestore_no_auto_restore', 13:17:54 INFO - u'tabpaint']}, 13:17:54 INFO - u'other-e10s': {u'tests': [u'a11yr', 13:17:54 INFO - u'ts_paint', 13:17:54 INFO - u'tpaint', 13:17:54 INFO - u'sessionrestore', 13:17:54 INFO - u'sessionrestore_no_auto_restore', 13:17:54 INFO - u'tabpaint']}, 13:17:54 INFO - u'other-e10s_l64': {u'tests': [u'a11yr', 13:17:54 INFO - u'ts_paint', 13:17:54 INFO - u'tpaint', 13:17:54 INFO - u'sessionrestore', 13:17:54 INFO - u'sessionrestore_no_auto_restore', 13:17:54 INFO - u'tabpaint']}, 13:17:54 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr', 13:17:54 INFO - u'ts_paint', 13:17:54 INFO - u'tpaint', 13:17:54 INFO - u'sessionrestore', 13:17:54 INFO - u'sessionrestore_no_auto_restore', 13:17:54 INFO - u'tabpaint']}, 13:17:54 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'], 13:17:54 INFO - u'tests': [u'a11yr', 13:17:54 INFO - u'ts_paint', 13:17:54 INFO - u'tpaint', 13:17:54 INFO - u'sessionrestore', 13:17:54 INFO - u'sessionrestore_no_auto_restore', 13:17:54 INFO - u'tabpaint']}, 13:17:54 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'], 13:17:54 INFO - u'tests': [u'a11yr', 13:17:54 INFO - u'ts_paint', 13:17:54 INFO - u'tpaint', 13:17:54 INFO - u'sessionrestore', 13:17:54 INFO - u'sessionrestore_no_auto_restore', 13:17:54 INFO - u'tabpaint']}, 13:17:54 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'], 13:17:54 INFO - u'tests': [u'tsvgx', 13:17:54 INFO - u'tsvgr_opacity', 13:17:54 INFO - u'tart', 13:17:54 INFO - u'tscrollx', 13:17:54 INFO - u'cart']}, 13:17:54 INFO - u'svgr-e10s': {u'tests': [u'tsvgx', 13:17:54 INFO - u'tsvgr_opacity', 13:17:54 INFO - u'tart', 13:17:54 INFO - u'tscrollx', 13:17:54 INFO - u'cart']}, 13:17:54 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:17:54 INFO - u'talos_options': [u'--disable-e10s'], 13:17:54 INFO - u'tests': [u'tp5o']}, 13:17:54 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:17:54 INFO - u'tests': [u'tp5o']}, 13:17:54 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:17:54 INFO - u'talos_options': [u'--disable-e10s', 13:17:54 INFO - u'--xperf_path', 13:17:54 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:17:54 INFO - u'tests': [u'tp5n']}, 13:17:54 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:17:54 INFO - u'talos_options': [u'--xperf_path', 13:17:54 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:17:54 INFO - u'tests': [u'tp5n']}}, 13:17:54 INFO - u'talos.zip': {u'path': u'', 13:17:54 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:17:54 INFO - Running post-action listener: _resource_record_post_action 13:17:54 INFO - [mozharness: 2016-10-31 20:17:54.771000Z] Finished populate-webroot step (success) 13:17:54 INFO - [mozharness: 2016-10-31 20:17:54.771000Z] Running create-virtualenv step. 13:17:54 INFO - Running pre-action listener: _resource_record_pre_action 13:17:54 INFO - Running main action method: create_virtualenv 13:17:54 INFO - Creating virtualenv C:\slave\test\build/venv 13:17:54 INFO - mkdir: C:\slave\test\build\venv\Scripts 13:17:54 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll 13:17: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'! 13:17: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 13:17: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 13:18:05 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe 13:18:10 INFO - Installing distribute......................................................................................................................................................................................done. 13:18:11 INFO - Return code: 0 13:18:11 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version'] 13:18:11 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version 13:18:12 INFO - Reading from file tmpfile_stdout 13:18:12 INFO - Output received: 13:18: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) 13:18:12 INFO - Using _rmtree_windows ... 13:18:12 INFO - Using _rmtree_windows ... 13:18:12 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv 13:18: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')]} 13:18:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:18: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')]} 13:18:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:18:12 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01888640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018BFE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01802B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018D9E90>, '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 0x0122A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01945520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-031', 'USERDOMAIN': 'T-W732-IX-031', '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-031', '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 13:18:12 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in C:\slave\test\build 13:18:12 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 13:18:12 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 13:18:12 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 13:18:12 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 13:18:12 INFO - 'COMPUTERNAME': 'T-W732-IX-031', 13:18:12 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 13:18:12 INFO - 'CYGWINBASE': 'C:\\cygwin', 13:18:12 INFO - 'DCLOCATION': 'SCL3', 13:18:12 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 13:18:12 INFO - 'FP_NO_HOST_CHECK': 'NO', 13:18:12 INFO - 'HOMEDRIVE': 'C:', 13:18:12 INFO - 'HOMEPATH': '\\Users\\cltbld', 13:18:12 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 13:18:12 INFO - 'KTS_VERSION': '1.19c', 13:18:12 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 13:18:12 INFO - 'LOGONSERVER': '\\\\T-W732-IX-031', 13:18:12 INFO - 'MONDIR': 'C:\\Monitor_config\\', 13:18:12 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 13:18:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:18:12 INFO - 'MOZ_NO_REMOTE': '1', 13:18:12 INFO - 'NO_EM_RESTART': '1', 13:18:12 INFO - 'NUMBER_OF_PROCESSORS': '8', 13:18:12 INFO - 'OS': 'Windows_NT', 13:18:12 INFO - 'OURDRIVE': 'C:', 13:18:12 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 13:18:12 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 13:18:12 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 13:18:12 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 13:18:12 INFO - 'PROCESSOR_LEVEL': '6', 13:18:12 INFO - 'PROCESSOR_REVISION': '1e05', 13:18:12 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 13:18:12 INFO - 'PROGRAMFILES': 'C:\\Program Files', 13:18:12 INFO - 'PROMPT': '$P$G', 13:18:12 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 13:18:12 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 13:18:12 INFO - 'PUBLIC': 'C:\\Users\\Public', 13:18:12 INFO - 'PWD': 'C:\\slave\\test', 13:18:12 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 13:18:12 INFO - 'SLAVEDIR': 'C:\\slave\\', 13:18:12 INFO - 'SYSTEMDRIVE': 'C:', 13:18:12 INFO - 'SYSTEMROOT': 'C:\\windows', 13:18:12 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 13:18:12 INFO - 'TEST1': 'testie', 13:18:12 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 13:18:12 INFO - 'USERDOMAIN': 'T-W732-IX-031', 13:18:12 INFO - 'USERNAME': 'cltbld', 13:18:12 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 13:18:12 INFO - 'WINDIR': 'C:\\windows', 13:18:12 INFO - 'WINDOWS_TRACING_FLAGS': '3', 13:18:12 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 13:18:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 13:18:13 INFO - Ignoring indexes: http://pypi.python.org/simple/ 13:18:13 INFO - Downloading/unpacking pip>=1.5 13:18:17 INFO - Running setup.py egg_info for package pip 13:18:17 INFO - warning: no files found matching 'pip\cacert.pem' 13:18:17 INFO - warning: no files found matching '*.html' under directory 'docs' 13:18:17 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 13:18:17 INFO - no previously-included directories found matching 'docs\_build\_sources' 13:18:17 INFO - Installing collected packages: pip 13:18:17 INFO - Found existing installation: pip 0.8.2 13:18:17 INFO - Uninstalling pip: 13:18:17 INFO - Successfully uninstalled pip 13:18:17 INFO - Running setup.py install for pip 13:18:17 INFO - warning: no files found matching 'pip\cacert.pem' 13:18:17 INFO - warning: no files found matching '*.html' under directory 'docs' 13:18:17 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 13:18:17 INFO - no previously-included directories found matching 'docs\_build\_sources' 13:18:17 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts 13:18:17 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts 13:18:17 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts 13:18:17 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts 13:18:17 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts 13:18:17 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts 13:18:17 INFO - Successfully installed pip 13:18:17 INFO - Cleaning up... 13:18:17 INFO - Return code: 0 13:18:17 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv 13:18:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:18:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:18:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:18:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:18:17 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x01888640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018BFE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01802B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018D9E90>, '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 0x0122A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01945520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-031', 'USERDOMAIN': 'T-W732-IX-031', '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-031', '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 13:18:17 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in C:\slave\test\build 13:18:17 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 13:18:17 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 13:18:17 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 13:18:17 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 13:18:17 INFO - 'COMPUTERNAME': 'T-W732-IX-031', 13:18:17 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 13:18:17 INFO - 'CYGWINBASE': 'C:\\cygwin', 13:18:17 INFO - 'DCLOCATION': 'SCL3', 13:18:17 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 13:18:17 INFO - 'FP_NO_HOST_CHECK': 'NO', 13:18:17 INFO - 'HOMEDRIVE': 'C:', 13:18:17 INFO - 'HOMEPATH': '\\Users\\cltbld', 13:18:17 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 13:18:17 INFO - 'KTS_VERSION': '1.19c', 13:18:17 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 13:18:17 INFO - 'LOGONSERVER': '\\\\T-W732-IX-031', 13:18:17 INFO - 'MONDIR': 'C:\\Monitor_config\\', 13:18:17 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 13:18:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:18:17 INFO - 'MOZ_NO_REMOTE': '1', 13:18:17 INFO - 'NO_EM_RESTART': '1', 13:18:17 INFO - 'NUMBER_OF_PROCESSORS': '8', 13:18:17 INFO - 'OS': 'Windows_NT', 13:18:17 INFO - 'OURDRIVE': 'C:', 13:18:17 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 13:18:17 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 13:18:17 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 13:18:17 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 13:18:17 INFO - 'PROCESSOR_LEVEL': '6', 13:18:17 INFO - 'PROCESSOR_REVISION': '1e05', 13:18:17 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 13:18:17 INFO - 'PROGRAMFILES': 'C:\\Program Files', 13:18:17 INFO - 'PROMPT': '$P$G', 13:18:17 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 13:18:17 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 13:18:17 INFO - 'PUBLIC': 'C:\\Users\\Public', 13:18:17 INFO - 'PWD': 'C:\\slave\\test', 13:18:17 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 13:18:17 INFO - 'SLAVEDIR': 'C:\\slave\\', 13:18:17 INFO - 'SYSTEMDRIVE': 'C:', 13:18:17 INFO - 'SYSTEMROOT': 'C:\\windows', 13:18:17 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 13:18:17 INFO - 'TEST1': 'testie', 13:18:17 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 13:18:17 INFO - 'USERDOMAIN': 'T-W732-IX-031', 13:18:17 INFO - 'USERNAME': 'cltbld', 13:18:17 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 13:18:17 INFO - 'WINDIR': 'C:\\windows', 13:18:17 INFO - 'WINDOWS_TRACING_FLAGS': '3', 13:18:17 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 13:18:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 13:18:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:18:19 INFO - Downloading/unpacking psutil>=3.1.1 13:18: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 13:18: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 13:18:20 INFO - Installing collected packages: psutil 13:18:20 INFO - Successfully installed psutil 13:18:20 INFO - Cleaning up... 13:18:20 INFO - Return code: 0 13:18:20 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv 13:18:20 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')]} 13:18:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:18:20 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')]} 13:18:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:18:20 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 0x01888640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018BFE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01802B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018D9E90>, '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 0x0122A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01945520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-031', 'USERDOMAIN': 'T-W732-IX-031', '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-031', '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 13:18:20 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 13:18:20 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 13:18:20 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 13:18:20 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 13:18:20 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 13:18:20 INFO - 'COMPUTERNAME': 'T-W732-IX-031', 13:18:20 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 13:18:20 INFO - 'CYGWINBASE': 'C:\\cygwin', 13:18:20 INFO - 'DCLOCATION': 'SCL3', 13:18:20 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 13:18:20 INFO - 'FP_NO_HOST_CHECK': 'NO', 13:18:20 INFO - 'HOMEDRIVE': 'C:', 13:18:20 INFO - 'HOMEPATH': '\\Users\\cltbld', 13:18:20 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 13:18:20 INFO - 'KTS_VERSION': '1.19c', 13:18:20 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 13:18:20 INFO - 'LOGONSERVER': '\\\\T-W732-IX-031', 13:18:20 INFO - 'MONDIR': 'C:\\Monitor_config\\', 13:18:20 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 13:18:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:18:20 INFO - 'MOZ_NO_REMOTE': '1', 13:18:20 INFO - 'NO_EM_RESTART': '1', 13:18:20 INFO - 'NUMBER_OF_PROCESSORS': '8', 13:18:20 INFO - 'OS': 'Windows_NT', 13:18:20 INFO - 'OURDRIVE': 'C:', 13:18:20 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;', 13:18:20 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 13:18:20 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 13:18:20 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 13:18:20 INFO - 'PROCESSOR_LEVEL': '6', 13:18:20 INFO - 'PROCESSOR_REVISION': '1e05', 13:18:20 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 13:18:20 INFO - 'PROGRAMFILES': 'C:\\Program Files', 13:18:20 INFO - 'PROMPT': '$P$G', 13:18:20 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 13:18:20 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 13:18:20 INFO - 'PUBLIC': 'C:\\Users\\Public', 13:18:20 INFO - 'PWD': 'C:\\slave\\test', 13:18:20 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 13:18:20 INFO - 'SLAVEDIR': 'C:\\slave\\', 13:18:20 INFO - 'SYSTEMDRIVE': 'C:', 13:18:20 INFO - 'SYSTEMROOT': 'C:\\windows', 13:18:20 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 13:18:20 INFO - 'TEST1': 'testie', 13:18:20 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 13:18:20 INFO - 'USERDOMAIN': 'T-W732-IX-031', 13:18:20 INFO - 'USERNAME': 'cltbld', 13:18:20 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 13:18:20 INFO - 'WINDIR': 'C:\\windows', 13:18:20 INFO - 'WINDOWS_TRACING_FLAGS': '3', 13:18:20 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 13:18:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 13:18:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:18:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:18: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 13:18: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 13:18:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:18:22 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 13:18: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) 13:18:22 INFO - Installing collected packages: mozsystemmonitor 13:18:22 INFO - Running setup.py install for mozsystemmonitor 13:18:22 INFO - Successfully installed mozsystemmonitor 13:18:22 INFO - Cleaning up... 13:18:22 INFO - Return code: 0 13:18:22 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv 13:18:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:18:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:18:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:18:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:18:22 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 0x01888640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018BFE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01802B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018D9E90>, '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 0x0122A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01945520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-031', 'USERDOMAIN': 'T-W732-IX-031', '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-031', '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 13:18:22 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 13:18:22 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 13:18:22 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 13:18:22 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 13:18:22 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 13:18:22 INFO - 'COMPUTERNAME': 'T-W732-IX-031', 13:18:22 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 13:18:22 INFO - 'CYGWINBASE': 'C:\\cygwin', 13:18:22 INFO - 'DCLOCATION': 'SCL3', 13:18:22 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 13:18:22 INFO - 'FP_NO_HOST_CHECK': 'NO', 13:18:22 INFO - 'HOMEDRIVE': 'C:', 13:18:22 INFO - 'HOMEPATH': '\\Users\\cltbld', 13:18:22 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 13:18:22 INFO - 'KTS_VERSION': '1.19c', 13:18:22 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 13:18:22 INFO - 'LOGONSERVER': '\\\\T-W732-IX-031', 13:18:22 INFO - 'MONDIR': 'C:\\Monitor_config\\', 13:18:22 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 13:18:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:18:22 INFO - 'MOZ_NO_REMOTE': '1', 13:18:22 INFO - 'NO_EM_RESTART': '1', 13:18:22 INFO - 'NUMBER_OF_PROCESSORS': '8', 13:18:22 INFO - 'OS': 'Windows_NT', 13:18:22 INFO - 'OURDRIVE': 'C:', 13:18:22 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 13:18:22 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 13:18:22 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 13:18:22 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 13:18:22 INFO - 'PROCESSOR_LEVEL': '6', 13:18:22 INFO - 'PROCESSOR_REVISION': '1e05', 13:18:22 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 13:18:22 INFO - 'PROGRAMFILES': 'C:\\Program Files', 13:18:22 INFO - 'PROMPT': '$P$G', 13:18:22 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 13:18:22 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 13:18:22 INFO - 'PUBLIC': 'C:\\Users\\Public', 13:18:22 INFO - 'PWD': 'C:\\slave\\test', 13:18:22 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 13:18:22 INFO - 'SLAVEDIR': 'C:\\slave\\', 13:18:22 INFO - 'SYSTEMDRIVE': 'C:', 13:18:22 INFO - 'SYSTEMROOT': 'C:\\windows', 13:18:22 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 13:18:22 INFO - 'TEST1': 'testie', 13:18:22 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 13:18:22 INFO - 'USERDOMAIN': 'T-W732-IX-031', 13:18:22 INFO - 'USERNAME': 'cltbld', 13:18:22 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 13:18:22 INFO - 'WINDIR': 'C:\\windows', 13:18:22 INFO - 'WINDOWS_TRACING_FLAGS': '3', 13:18:22 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 13:18:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 13:18:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:18:23 INFO - Downloading/unpacking jsonschema==2.5.1 13:18:23 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 13:18:23 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 13:18:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:18:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:18: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 13:18: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 13:18:24 INFO - Downloading functools32-3.2.3-2.tar.gz 13:18:24 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32 13:18:24 INFO - warning: no files found matching '*.txt' 13:18:24 INFO - no previously-included directories found matching 'build' 13:18:24 INFO - no previously-included directories found matching 'dist' 13:18:24 INFO - no previously-included directories found matching '.git*' 13:18:24 INFO - Installing collected packages: jsonschema, functools32 13:18:24 INFO - Running setup.py install for functools32 13:18:24 INFO - warning: no files found matching '*.txt' 13:18:24 INFO - no previously-included directories found matching 'build' 13:18:24 INFO - no previously-included directories found matching 'dist' 13:18:24 INFO - no previously-included directories found matching '.git*' 13:18:24 INFO - Successfully installed jsonschema functools32 13:18:24 INFO - Cleaning up... 13:18:25 INFO - Return code: 0 13:18:25 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv 13:18:25 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')]} 13:18:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:18:25 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')]} 13:18:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:18:25 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 0x01888640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018BFE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01802B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018D9E90>, '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 0x0122A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01945520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-031', 'USERDOMAIN': 'T-W732-IX-031', '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-031', '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 13:18:25 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 13:18:25 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 13:18:25 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 13:18:25 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 13:18:25 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 13:18:25 INFO - 'COMPUTERNAME': 'T-W732-IX-031', 13:18:25 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 13:18:25 INFO - 'CYGWINBASE': 'C:\\cygwin', 13:18:25 INFO - 'DCLOCATION': 'SCL3', 13:18:25 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 13:18:25 INFO - 'FP_NO_HOST_CHECK': 'NO', 13:18:25 INFO - 'HOMEDRIVE': 'C:', 13:18:25 INFO - 'HOMEPATH': '\\Users\\cltbld', 13:18:25 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 13:18:25 INFO - 'KTS_VERSION': '1.19c', 13:18:25 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 13:18:25 INFO - 'LOGONSERVER': '\\\\T-W732-IX-031', 13:18:25 INFO - 'MONDIR': 'C:\\Monitor_config\\', 13:18:25 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 13:18:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:18:25 INFO - 'MOZ_NO_REMOTE': '1', 13:18:25 INFO - 'NO_EM_RESTART': '1', 13:18:25 INFO - 'NUMBER_OF_PROCESSORS': '8', 13:18:25 INFO - 'OS': 'Windows_NT', 13:18:25 INFO - 'OURDRIVE': 'C:', 13:18:25 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;', 13:18:25 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 13:18:25 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 13:18:25 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 13:18:25 INFO - 'PROCESSOR_LEVEL': '6', 13:18:25 INFO - 'PROCESSOR_REVISION': '1e05', 13:18:25 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 13:18:25 INFO - 'PROGRAMFILES': 'C:\\Program Files', 13:18:25 INFO - 'PROMPT': '$P$G', 13:18:25 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 13:18:25 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 13:18:25 INFO - 'PUBLIC': 'C:\\Users\\Public', 13:18:25 INFO - 'PWD': 'C:\\slave\\test', 13:18:25 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 13:18:25 INFO - 'SLAVEDIR': 'C:\\slave\\', 13:18:25 INFO - 'SYSTEMDRIVE': 'C:', 13:18:25 INFO - 'SYSTEMROOT': 'C:\\windows', 13:18:25 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 13:18:25 INFO - 'TEST1': 'testie', 13:18:25 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 13:18:25 INFO - 'USERDOMAIN': 'T-W732-IX-031', 13:18:25 INFO - 'USERNAME': 'cltbld', 13:18:25 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 13:18:25 INFO - 'WINDIR': 'C:\\windows', 13:18:25 INFO - 'WINDOWS_TRACING_FLAGS': '3', 13:18:25 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 13:18:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 13:18:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:18:25 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages 13:18:25 INFO - Cleaning up... 13:18:25 INFO - Return code: 0 13:18:25 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv 13:18:25 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')]} 13:18:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:18:25 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')]} 13:18:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:18:25 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 0x01888640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018BFE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01802B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018D9E90>, '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 0x0122A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01945520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-031', 'USERDOMAIN': 'T-W732-IX-031', '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-031', '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 13:18:25 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 13:18:25 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 13:18:25 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 13:18:25 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 13:18:25 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 13:18:25 INFO - 'COMPUTERNAME': 'T-W732-IX-031', 13:18:25 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 13:18:25 INFO - 'CYGWINBASE': 'C:\\cygwin', 13:18:25 INFO - 'DCLOCATION': 'SCL3', 13:18:25 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 13:18:25 INFO - 'FP_NO_HOST_CHECK': 'NO', 13:18:25 INFO - 'HOMEDRIVE': 'C:', 13:18:25 INFO - 'HOMEPATH': '\\Users\\cltbld', 13:18:25 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 13:18:25 INFO - 'KTS_VERSION': '1.19c', 13:18:25 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 13:18:25 INFO - 'LOGONSERVER': '\\\\T-W732-IX-031', 13:18:25 INFO - 'MONDIR': 'C:\\Monitor_config\\', 13:18:25 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 13:18:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:18:25 INFO - 'MOZ_NO_REMOTE': '1', 13:18:25 INFO - 'NO_EM_RESTART': '1', 13:18:25 INFO - 'NUMBER_OF_PROCESSORS': '8', 13:18:25 INFO - 'OS': 'Windows_NT', 13:18:25 INFO - 'OURDRIVE': 'C:', 13:18:25 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;', 13:18:25 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 13:18:25 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 13:18:25 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 13:18:25 INFO - 'PROCESSOR_LEVEL': '6', 13:18:25 INFO - 'PROCESSOR_REVISION': '1e05', 13:18:25 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 13:18:25 INFO - 'PROGRAMFILES': 'C:\\Program Files', 13:18:25 INFO - 'PROMPT': '$P$G', 13:18:25 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 13:18:25 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 13:18:25 INFO - 'PUBLIC': 'C:\\Users\\Public', 13:18:25 INFO - 'PWD': 'C:\\slave\\test', 13:18:25 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 13:18:25 INFO - 'SLAVEDIR': 'C:\\slave\\', 13:18:25 INFO - 'SYSTEMDRIVE': 'C:', 13:18:25 INFO - 'SYSTEMROOT': 'C:\\windows', 13:18:25 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 13:18:25 INFO - 'TEST1': 'testie', 13:18:25 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 13:18:25 INFO - 'USERDOMAIN': 'T-W732-IX-031', 13:18:25 INFO - 'USERNAME': 'cltbld', 13:18:25 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 13:18:25 INFO - 'WINDIR': 'C:\\windows', 13:18:25 INFO - 'WINDOWS_TRACING_FLAGS': '3', 13:18:25 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 13:18:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 13:18:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:18:25 INFO - Downloading/unpacking blobuploader==1.2.4 13:18: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 13:18: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 13:18:26 INFO - Downloading blobuploader-1.2.4.tar.gz 13:18:26 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 13:18:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:18:26 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 13:18:26 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 13:18:27 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests 13:18:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:18: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 13:18: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 13:18:27 INFO - Downloading docopt-0.6.1.tar.gz 13:18:27 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt 13:18:27 INFO - Installing collected packages: blobuploader, requests, docopt 13:18:27 INFO - Running setup.py install for blobuploader 13:18:27 INFO - Running setup.py install for requests 13:18:28 INFO - Running setup.py install for docopt 13:18:28 INFO - Successfully installed blobuploader requests docopt 13:18:28 INFO - Cleaning up... 13:18:29 INFO - Return code: 0 13:18:29 INFO - Installing None into virtualenv C:\slave\test\build/venv 13:18:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:18:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:18:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:18:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:18:29 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 0x01888640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018BFE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01802B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018D9E90>, '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 0x0122A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01945520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-031', 'USERDOMAIN': 'T-W732-IX-031', '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-031', '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 13:18:29 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 13:18:29 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 13:18:29 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 13:18:29 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 13:18:29 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 13:18:29 INFO - 'COMPUTERNAME': 'T-W732-IX-031', 13:18:29 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 13:18:29 INFO - 'CYGWINBASE': 'C:\\cygwin', 13:18:29 INFO - 'DCLOCATION': 'SCL3', 13:18:29 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 13:18:29 INFO - 'FP_NO_HOST_CHECK': 'NO', 13:18:29 INFO - 'HOMEDRIVE': 'C:', 13:18:29 INFO - 'HOMEPATH': '\\Users\\cltbld', 13:18:29 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 13:18:29 INFO - 'KTS_VERSION': '1.19c', 13:18:29 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 13:18:29 INFO - 'LOGONSERVER': '\\\\T-W732-IX-031', 13:18:29 INFO - 'MONDIR': 'C:\\Monitor_config\\', 13:18:29 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 13:18:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:18:29 INFO - 'MOZ_NO_REMOTE': '1', 13:18:29 INFO - 'NO_EM_RESTART': '1', 13:18:29 INFO - 'NUMBER_OF_PROCESSORS': '8', 13:18:29 INFO - 'OS': 'Windows_NT', 13:18:29 INFO - 'OURDRIVE': 'C:', 13:18:29 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 13:18:29 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 13:18:29 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 13:18:29 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 13:18:29 INFO - 'PROCESSOR_LEVEL': '6', 13:18:29 INFO - 'PROCESSOR_REVISION': '1e05', 13:18:29 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 13:18:29 INFO - 'PROGRAMFILES': 'C:\\Program Files', 13:18:29 INFO - 'PROMPT': '$P$G', 13:18:29 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 13:18:29 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 13:18:29 INFO - 'PUBLIC': 'C:\\Users\\Public', 13:18:29 INFO - 'PWD': 'C:\\slave\\test', 13:18:29 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 13:18:29 INFO - 'SLAVEDIR': 'C:\\slave\\', 13:18:29 INFO - 'SYSTEMDRIVE': 'C:', 13:18:29 INFO - 'SYSTEMROOT': 'C:\\windows', 13:18:29 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 13:18:29 INFO - 'TEST1': 'testie', 13:18:29 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 13:18:29 INFO - 'USERDOMAIN': 'T-W732-IX-031', 13:18:29 INFO - 'USERNAME': 'cltbld', 13:18:29 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 13:18:29 INFO - 'WINDIR': 'C:\\windows', 13:18:29 INFO - 'WINDOWS_TRACING_FLAGS': '3', 13:18:29 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 13:18:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 13:18:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:18:30 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 13:18:30 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-sflqhl-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 13:18:30 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 13:18:30 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bgsaat-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 13:18:30 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 13:18:30 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-6odhsd-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 13:18:30 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 13:18:30 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bf7y5p-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 13:18:30 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 13:18:30 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-suj6w_-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 13:18:30 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 13:18:30 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ehadx9-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 13:18:31 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 13:18:31 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-so79q1-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 13:18:31 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 13:18:31 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-p7u2ai-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 13:18:31 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 13:18:31 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ozqyfk-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 13:18:31 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 13:18:31 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-uthj02-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 13:18:31 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 13:18:32 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hcbmcq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 13:18:32 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 13:18:32 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hztcog-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 13:18:32 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 13:18:32 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gcusld-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 13:18:32 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 13:18:32 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-7fkbb8-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 13:18:32 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 13:18:32 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-o2ekck-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 13:18:32 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 13:18:32 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-upw8nv-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 13:18:32 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 13:18:32 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ar8emr-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 13:18:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:18:32 INFO - Running setup.py install for manifestparser 13:18:33 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts 13:18:33 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts 13:18:33 INFO - Running setup.py install for mozcrash 13:18:34 INFO - Running setup.py install for mozdebug 13:18:34 INFO - Running setup.py install for mozdevice 13:18:34 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts 13:18:34 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts 13:18:34 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts 13:18:34 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts 13:18:35 INFO - Running setup.py install for mozfile 13:18:35 INFO - Running setup.py install for mozhttpd 13:18:35 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts 13:18:35 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts 13:18:36 INFO - Running setup.py install for mozinfo 13:18:36 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts 13:18:36 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts 13:18:36 INFO - Running setup.py install for mozInstall 13:18:36 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts 13:18:36 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts 13:18:36 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts 13:18:36 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts 13:18:36 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts 13:18:36 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts 13:18:36 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts 13:18:36 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts 13:18:36 INFO - Running setup.py install for mozleak 13:18:37 INFO - Running setup.py install for mozlog 13:18:37 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts 13:18:37 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts 13:18:37 INFO - Running setup.py install for moznetwork 13:18:38 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts 13:18:38 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts 13:18:38 INFO - Running setup.py install for mozprocess 13:18:38 INFO - Running setup.py install for mozprofile 13:18:39 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts 13:18:39 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts 13:18:39 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts 13:18:39 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts 13:18:39 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts 13:18:39 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts 13:18:39 INFO - Running setup.py install for mozrunner 13:18:39 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts 13:18:39 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts 13:18:40 INFO - Running setup.py install for mozscreenshot 13:18:40 INFO - Running setup.py install for moztest 13:18:40 INFO - Running setup.py install for mozversion 13:18:41 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts 13:18:41 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts 13:18:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:18:41 INFO - Cleaning up... 13:18:41 INFO - Return code: 0 13:18:41 INFO - Installing None into virtualenv C:\slave\test\build/venv 13:18:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:18:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:18:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:18:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:18:41 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 0x01888640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018BFE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01802B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018D9E90>, '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 0x0122A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01945520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-031', 'USERDOMAIN': 'T-W732-IX-031', '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-031', '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 13:18:41 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 13:18:41 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 13:18:41 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 13:18:41 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 13:18:41 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 13:18:41 INFO - 'COMPUTERNAME': 'T-W732-IX-031', 13:18:41 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 13:18:41 INFO - 'CYGWINBASE': 'C:\\cygwin', 13:18:41 INFO - 'DCLOCATION': 'SCL3', 13:18:41 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 13:18:41 INFO - 'FP_NO_HOST_CHECK': 'NO', 13:18:41 INFO - 'HOMEDRIVE': 'C:', 13:18:41 INFO - 'HOMEPATH': '\\Users\\cltbld', 13:18:41 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 13:18:41 INFO - 'KTS_VERSION': '1.19c', 13:18:41 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 13:18:41 INFO - 'LOGONSERVER': '\\\\T-W732-IX-031', 13:18:41 INFO - 'MONDIR': 'C:\\Monitor_config\\', 13:18:41 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 13:18:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:18:41 INFO - 'MOZ_NO_REMOTE': '1', 13:18:41 INFO - 'NO_EM_RESTART': '1', 13:18:41 INFO - 'NUMBER_OF_PROCESSORS': '8', 13:18:41 INFO - 'OS': 'Windows_NT', 13:18:41 INFO - 'OURDRIVE': 'C:', 13:18:41 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 13:18:41 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 13:18:41 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 13:18:41 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 13:18:41 INFO - 'PROCESSOR_LEVEL': '6', 13:18:41 INFO - 'PROCESSOR_REVISION': '1e05', 13:18:41 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 13:18:41 INFO - 'PROGRAMFILES': 'C:\\Program Files', 13:18:41 INFO - 'PROMPT': '$P$G', 13:18:41 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 13:18:41 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 13:18:41 INFO - 'PUBLIC': 'C:\\Users\\Public', 13:18:41 INFO - 'PWD': 'C:\\slave\\test', 13:18:41 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 13:18:41 INFO - 'SLAVEDIR': 'C:\\slave\\', 13:18:41 INFO - 'SYSTEMDRIVE': 'C:', 13:18:41 INFO - 'SYSTEMROOT': 'C:\\windows', 13:18:41 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 13:18:41 INFO - 'TEST1': 'testie', 13:18:41 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 13:18:41 INFO - 'USERDOMAIN': 'T-W732-IX-031', 13:18:41 INFO - 'USERNAME': 'cltbld', 13:18:41 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 13:18:41 INFO - 'WINDIR': 'C:\\windows', 13:18:41 INFO - 'WINDOWS_TRACING_FLAGS': '3', 13:18:41 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 13:18:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 13:18:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:18:41 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 13:18:41 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hyktp_-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 13:18:42 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)) 13:18:42 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 13:18:42 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-e2jf6i-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 13:18:42 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)) 13:18:42 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 13:18:42 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-amp3cx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 13:18:42 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)) 13:18:42 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 13:18:42 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-j190fw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 13:18:42 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)) 13:18:42 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 13:18:42 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-3kwezs-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 13:18:42 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)) 13:18:42 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 13:18:42 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-__p7nu-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 13:18:42 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)) 13:18:42 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 13:18:42 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ljunuz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 13:18:42 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)) 13:18:42 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 13:18:42 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-snd_3z-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 13:18:43 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)) 13:18:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 13:18:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-1ik9ax-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 13:18:43 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)) 13:18:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 13:18:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fx7o55-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 13:18:43 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)) 13:18:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 13:18:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-nefnzm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 13:18:43 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)) 13:18:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 13:18:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-90h1le-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 13:18:43 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)) 13:18:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 13:18:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-uql713-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 13:18:43 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)) 13:18:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 13:18:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-q1ewbs-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 13:18:43 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)) 13:18:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 13:18:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-splamx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 13:18:44 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)) 13:18:44 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 13:18:44 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-z8kxtf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 13:18:44 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)) 13:18:44 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 13:18:44 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mr0rfu-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 13:18:44 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)) 13:18:44 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)) 13:18:44 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)) 13:18:44 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)) 13:18:44 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)) 13:18:44 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)) 13:18:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10)) 13:18:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:18:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:18:45 INFO - Downloading blessings-1.6.tar.gz 13:18:45 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings 13:18:45 INFO - Installing collected packages: blessings 13:18:45 INFO - Running setup.py install for blessings 13:18:46 INFO - Successfully installed blessings 13:18:46 INFO - Cleaning up... 13:18:48 INFO - Return code: 0 13:18:48 INFO - Done creating virtualenv C:\slave\test\build/venv. 13:18:48 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 13:18:48 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 13:18:49 INFO - Reading from file tmpfile_stdout 13:18:49 INFO - Using _rmtree_windows ... 13:18:49 INFO - Using _rmtree_windows ... 13:18:49 INFO - Current package versions: 13:18:49 INFO - blessings == 1.6 13:18:49 INFO - blobuploader == 1.2.4 13:18:49 INFO - docopt == 0.6.1 13:18:49 INFO - functools32 == 3.2.3-2 13:18:49 INFO - jsonschema == 2.5.1 13:18:49 INFO - manifestparser == 1.1 13:18:49 INFO - mozInstall == 1.12 13:18:49 INFO - mozcrash == 0.17 13:18:49 INFO - mozdebug == 0.1 13:18:49 INFO - mozdevice == 0.48 13:18:49 INFO - mozfile == 1.2 13:18:49 INFO - mozhttpd == 0.7 13:18:49 INFO - mozinfo == 0.9 13:18:49 INFO - mozleak == 0.1 13:18:49 INFO - mozlog == 3.4 13:18:49 INFO - moznetwork == 0.27 13:18:49 INFO - mozprocess == 0.23 13:18:49 INFO - mozprofile == 0.28 13:18:49 INFO - mozrunner == 6.12 13:18:49 INFO - mozscreenshot == 0.1 13:18:49 INFO - mozsystemmonitor == 0.3 13:18:49 INFO - moztest == 0.7 13:18:49 INFO - mozversion == 1.4 13:18:49 INFO - psutil == 3.1.1 13:18:49 INFO - requests == 1.2.3 13:18:49 INFO - Installing None into virtualenv C:\slave\test\build/venv 13:18:49 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')]} 13:18:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:18:49 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')]} 13:18:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:18:49 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 0x01888640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018BFE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01802B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018D9E90>, '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 0x0122A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01945520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-031', 'USERDOMAIN': 'T-W732-IX-031', '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-031', '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 13:18:49 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 13:18:49 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 13:18:49 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 13:18:49 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 13:18:49 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 13:18:49 INFO - 'COMPUTERNAME': 'T-W732-IX-031', 13:18:49 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 13:18:49 INFO - 'CYGWINBASE': 'C:\\cygwin', 13:18:49 INFO - 'DCLOCATION': 'SCL3', 13:18:49 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 13:18:49 INFO - 'FP_NO_HOST_CHECK': 'NO', 13:18:49 INFO - 'HOMEDRIVE': 'C:', 13:18:49 INFO - 'HOMEPATH': '\\Users\\cltbld', 13:18:49 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 13:18:49 INFO - 'KTS_VERSION': '1.19c', 13:18:49 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 13:18:49 INFO - 'LOGONSERVER': '\\\\T-W732-IX-031', 13:18:49 INFO - 'MONDIR': 'C:\\Monitor_config\\', 13:18:49 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 13:18:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:18:49 INFO - 'MOZ_NO_REMOTE': '1', 13:18:49 INFO - 'NO_EM_RESTART': '1', 13:18:49 INFO - 'NUMBER_OF_PROCESSORS': '8', 13:18:49 INFO - 'OS': 'Windows_NT', 13:18:49 INFO - 'OURDRIVE': 'C:', 13:18:49 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;', 13:18:49 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 13:18:49 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 13:18:49 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 13:18:49 INFO - 'PROCESSOR_LEVEL': '6', 13:18:49 INFO - 'PROCESSOR_REVISION': '1e05', 13:18:49 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 13:18:49 INFO - 'PROGRAMFILES': 'C:\\Program Files', 13:18:49 INFO - 'PROMPT': '$P$G', 13:18:49 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 13:18:49 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 13:18:49 INFO - 'PUBLIC': 'C:\\Users\\Public', 13:18:49 INFO - 'PWD': 'C:\\slave\\test', 13:18:49 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 13:18:49 INFO - 'SLAVEDIR': 'C:\\slave\\', 13:18:49 INFO - 'SYSTEMDRIVE': 'C:', 13:18:49 INFO - 'SYSTEMROOT': 'C:\\windows', 13:18:49 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 13:18:49 INFO - 'TEST1': 'testie', 13:18:49 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 13:18:49 INFO - 'USERDOMAIN': 'T-W732-IX-031', 13:18:49 INFO - 'USERNAME': 'cltbld', 13:18:49 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 13:18:49 INFO - 'WINDIR': 'C:\\windows', 13:18:49 INFO - 'WINDOWS_TRACING_FLAGS': '3', 13:18:49 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 13:18:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 13:18:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:18:49 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)) 13:18:49 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)) 13:18:49 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)) 13:18:49 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)) 13:18:49 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)) 13:18:49 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)) 13:18:49 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)) 13:18:49 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)) 13:18:49 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)) 13:18:49 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)) 13:18:49 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)) 13:18:49 INFO - Cleaning up... 13:18:49 INFO - Return code: 0 13:18:49 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv 13:18:49 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')]} 13:18:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:18:49 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')]} 13:18:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:18:49 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 0x01888640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018BFE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01802B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018D9E90>, '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 0x0122A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01945520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-031', 'USERDOMAIN': 'T-W732-IX-031', '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-031', '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 13:18:49 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 13:18:49 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 13:18:49 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 13:18:49 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 13:18:49 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 13:18:49 INFO - 'COMPUTERNAME': 'T-W732-IX-031', 13:18:49 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 13:18:49 INFO - 'CYGWINBASE': 'C:\\cygwin', 13:18:49 INFO - 'DCLOCATION': 'SCL3', 13:18:49 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 13:18:49 INFO - 'FP_NO_HOST_CHECK': 'NO', 13:18:49 INFO - 'HOMEDRIVE': 'C:', 13:18:49 INFO - 'HOMEPATH': '\\Users\\cltbld', 13:18:49 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 13:18:49 INFO - 'KTS_VERSION': '1.19c', 13:18:49 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 13:18:49 INFO - 'LOGONSERVER': '\\\\T-W732-IX-031', 13:18:49 INFO - 'MONDIR': 'C:\\Monitor_config\\', 13:18:49 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 13:18:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:18:49 INFO - 'MOZ_NO_REMOTE': '1', 13:18:49 INFO - 'NO_EM_RESTART': '1', 13:18:49 INFO - 'NUMBER_OF_PROCESSORS': '8', 13:18:49 INFO - 'OS': 'Windows_NT', 13:18:49 INFO - 'OURDRIVE': 'C:', 13:18:49 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;', 13:18:49 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 13:18:49 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 13:18:49 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 13:18:49 INFO - 'PROCESSOR_LEVEL': '6', 13:18:49 INFO - 'PROCESSOR_REVISION': '1e05', 13:18:49 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 13:18:49 INFO - 'PROGRAMFILES': 'C:\\Program Files', 13:18:49 INFO - 'PROMPT': '$P$G', 13:18:49 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 13:18:49 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 13:18:49 INFO - 'PUBLIC': 'C:\\Users\\Public', 13:18:49 INFO - 'PWD': 'C:\\slave\\test', 13:18:49 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 13:18:49 INFO - 'SLAVEDIR': 'C:\\slave\\', 13:18:49 INFO - 'SYSTEMDRIVE': 'C:', 13:18:49 INFO - 'SYSTEMROOT': 'C:\\windows', 13:18:49 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 13:18:49 INFO - 'TEST1': 'testie', 13:18:49 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 13:18:49 INFO - 'USERDOMAIN': 'T-W732-IX-031', 13:18:49 INFO - 'USERNAME': 'cltbld', 13:18:49 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 13:18:49 INFO - 'WINDIR': 'C:\\windows', 13:18:49 INFO - 'WINDOWS_TRACING_FLAGS': '3', 13:18:49 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 13:18:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 13:18:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:18:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages 13:18:50 INFO - Cleaning up... 13:18:50 INFO - Return code: 0 13:18:50 INFO - Running post-action listener: _resource_record_post_action 13:18:50 INFO - Running post-action listener: _start_resource_monitoring 13:18:50 INFO - Starting resource monitoring. 13:18:50 INFO - [mozharness: 2016-10-31 20:18:50.220000Z] Finished create-virtualenv step (success) 13:18:50 INFO - [mozharness: 2016-10-31 20:18:50.220000Z] Running install step. 13:18:50 INFO - Running pre-action listener: _resource_record_pre_action 13:18:50 INFO - Running main action method: install 13:18:50 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 13:18:50 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 13:18:50 INFO - Reading from file tmpfile_stdout 13:18:50 INFO - Using _rmtree_windows ... 13:18:50 INFO - Using _rmtree_windows ... 13:18:50 INFO - Detecting whether we're running mozinstall >=1.0... 13:18:50 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h'] 13:18:50 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h 13:18:50 INFO - Reading from file tmpfile_stdout 13:18:50 INFO - Output received: 13:18:50 INFO - Usage: mozinstall-script.py [options] installer 13:18:50 INFO - Options: 13:18:50 INFO - -h, --help show this help message and exit 13:18:50 INFO - -d DEST, --destination=DEST 13:18:50 INFO - Directory to install application into. [default: 13:18:50 INFO - "C:\slave\test"] 13:18:50 INFO - --app=APP Application being installed. [default: firefox] 13:18:50 INFO - Using _rmtree_windows ... 13:18:50 INFO - Using _rmtree_windows ... 13:18:50 INFO - mkdir: C:\slave\test\build\application 13:18:50 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'] 13:18:50 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 13:18:52 INFO - Reading from file tmpfile_stdout 13:18:52 INFO - Output received: 13:18:52 INFO - C:\slave\test\build\application\firefox\firefox.exe 13:18:52 INFO - Using _rmtree_windows ... 13:18:52 INFO - Using _rmtree_windows ... 13:18:52 INFO - Running post-action listener: _resource_record_post_action 13:18:52 INFO - [mozharness: 2016-10-31 20:18:52.961000Z] Finished install step (success) 13:18:52 INFO - [mozharness: 2016-10-31 20:18:52.961000Z] Running run-tests step. 13:18:52 INFO - Running pre-action listener: _resource_record_pre_action 13:18:52 INFO - Running main action method: run_tests 13:18:52 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version'] 13:18:52 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version 13:18:52 INFO - Python 2.7.3 13:18:52 INFO - Return code: 0 13:18:52 INFO - grabbing minidump binary from tooltool 13:18: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')]} 13:18:52 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 0x018D9E90>, '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 0x0122A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01945520>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1 13:18:52 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 13:18:52 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 13:18:52 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 13:18:53 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 13:18:53 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmpfrkuo4 13:18:53 INFO - INFO - File integrity verified, renaming tmpfrkuo4 to win32-minidump_stackwalk.exe 13:18:53 INFO - Return code: 0 13:18:53 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755 13:18:53 INFO - mkdir: C:\slave\test\build\blobber_upload_dir 13:18:53 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir 13:18:53 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe 13:18:53 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir 13:18:53 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IGvUpc-MTVW6MYT8SS5gxw/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-031', '--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 13:18:53 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --branchName Firefox --suite dromaeojs --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/IGvUpc-MTVW6MYT8SS5gxw/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-031 --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 13:18:53 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 13:18:53 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 13:18:53 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 13:18:53 INFO - 'COMPUTERNAME': 'T-W732-IX-031', 13:18:53 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 13:18:53 INFO - 'CYGWINBASE': 'C:\\cygwin', 13:18:53 INFO - 'DCLOCATION': 'SCL3', 13:18:53 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 13:18:53 INFO - 'FP_NO_HOST_CHECK': 'NO', 13:18:53 INFO - 'HOMEDRIVE': 'C:', 13:18:53 INFO - 'HOMEPATH': '\\Users\\cltbld', 13:18:53 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 13:18:53 INFO - 'KTS_VERSION': '1.19c', 13:18:53 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 13:18:53 INFO - 'LOGONSERVER': '\\\\T-W732-IX-031', 13:18:53 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir', 13:18:53 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe', 13:18:53 INFO - 'MONDIR': 'C:\\Monitor_config\\', 13:18:53 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 13:18:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:18:53 INFO - 'MOZ_NO_REMOTE': '1', 13:18:53 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir', 13:18:53 INFO - 'NO_EM_RESTART': '1', 13:18:53 INFO - 'NUMBER_OF_PROCESSORS': '8', 13:18:53 INFO - 'OS': 'Windows_NT', 13:18:53 INFO - 'OURDRIVE': 'C:', 13:18:53 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;', 13:18:53 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 13:18:53 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 13:18:53 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 13:18:53 INFO - 'PROCESSOR_LEVEL': '6', 13:18:53 INFO - 'PROCESSOR_REVISION': '1e05', 13:18:53 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 13:18:53 INFO - 'PROGRAMFILES': 'C:\\Program Files', 13:18:53 INFO - 'PROMPT': '$P$G', 13:18:53 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 13:18:53 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 13:18:53 INFO - 'PUBLIC': 'C:\\Users\\Public', 13:18:53 INFO - 'PWD': 'C:\\slave\\test', 13:18:53 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos', 13:18:53 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 13:18:53 INFO - 'SLAVEDIR': 'C:\\slave\\', 13:18:53 INFO - 'SYSTEMDRIVE': 'C:', 13:18:53 INFO - 'SYSTEMROOT': 'C:\\windows', 13:18:53 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 13:18:53 INFO - 'TEST1': 'testie', 13:18:53 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 13:18:53 INFO - 'USERDOMAIN': 'T-W732-IX-031', 13:18:53 INFO - 'USERNAME': 'cltbld', 13:18:53 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 13:18:53 INFO - 'WINDIR': 'C:\\windows', 13:18:53 INFO - 'WINDOWS_TRACING_FLAGS': '3', 13:18:53 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 13:18:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 13:18:53 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IGvUpc-MTVW6MYT8SS5gxw/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-031', '--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 13:18:54 INFO - mozversion application_buildid: 20161031103102 13:18:54 INFO - mozversion application_changeset: 8c9eed5227f8681cf08d2ee8fb3bfd5d743e4096 13:18:54 INFO - mozversion application_display_name: Nightly 13:18:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:18:54 INFO - mozversion application_name: Firefox 13:18:54 INFO - mozversion application_remotingname: firefox 13:18:54 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 13:18:54 INFO - mozversion application_vendor: Mozilla 13:18:54 INFO - mozversion application_version: 52.0a1 13:18:54 INFO - mozversion platform_buildid: 20161031103102 13:18:54 INFO - mozversion platform_changeset: 8c9eed5227f8681cf08d2ee8fb3bfd5d743e4096 13:18:54 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 13:18:54 INFO - mozversion platform_version: 52.0a1 13:18:54 INFO - using testdate: 1477945134 13:18:54 INFO - actual date: 1477945134 13:18:54 INFO - starting webserver on 'localhost:49668' 13:18:54 INFO - SUITE-START | Running 2 tests 13:18:54 INFO - TEST-START | dromaeo_css 13:18:54 INFO - operating with platform_type : w7_ 13:18:54 INFO - Initialising browser for dromaeo_css test... 13:18:55 INFO - TEST-INFO | started process 3252 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpxzrtup\profile http://localhost:49668/getInfo.html) 13:19:03 INFO - PROCESS | 3252 | __metrics Screen width/height:1600/1200 13:19:03 INFO - PROCESS | 3252 | colorDepth:24 13:19:03 INFO - PROCESS | 3252 | Browser inner width/height: 1010/674 13:19:03 INFO - PROCESS | 3252 | __metrics 13:19:03 INFO - PROCESS | 3252 | 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" 13:19:03 INFO - PROCESS | 3252 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:19:05 INFO - TEST-INFO | 3252: exit 0 13:19:05 INFO - Browser initialized. 13:19:05 INFO - Running cycle 1/1 for dromaeo_css test... 13:19:05 INFO - TEST-INFO | started process 1780 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpxzrtup\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:19:05 INFO - PROCESS | 1780 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0 13:19:16 INFO - PROCESS | 1780 | RSS: Main: 124841984 13:19:16 INFO - PROCESS | 1780 | 13:20:07 INFO - PROCESS | 1780 | 1477945207544 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] 13:22:17 INFO - PROCESS | 1780 | Cycle 1(1): loaded http://localhost:49668/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49668/tests/dromaeo/cssquery-ext.html) 13:22:20 INFO - PROCESS | 1780 | RSS: Main: 308207616 13:22:20 INFO - PROCESS | 1780 | 13:25:22 INFO - PROCESS | 1780 | Cycle 1(1): loaded http://localhost:49668/tests/dromaeo/cssquery-ext.html (next: http://localhost:49668/tests/dromaeo/cssquery-jquery.html) 13:25:23 INFO - PROCESS | 1780 | RSS: Main: 196124672 13:25:23 INFO - PROCESS | 1780 | 13:28:25 INFO - PROCESS | 1780 | Cycle 1(1): loaded http://localhost:49668/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49668/tests/dromaeo/cssquery-mootools.html) 13:28:25 INFO - PROCESS | 1780 | RSS: Main: 147456000 13:28:25 INFO - PROCESS | 1780 | 13:31:27 INFO - PROCESS | 1780 | Cycle 1(1): loaded http://localhost:49668/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49668/tests/dromaeo/cssquery-prototype.html) 13:31:28 INFO - PROCESS | 1780 | RSS: Main: 133095424 13:31:28 INFO - PROCESS | 1780 | 13:34:31 INFO - PROCESS | 1780 | Cycle 1(1): loaded http://localhost:49668/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49668/tests/dromaeo/cssquery-yui.html) 13:34:31 INFO - PROCESS | 1780 | RSS: Main: 133492736 13:34:31 INFO - PROCESS | 1780 | 13:37:34 INFO - PROCESS | 1780 | Cycle 1(1): loaded http://localhost:49668/tests/dromaeo/cssquery-yui.html (next: http://localhost:49668/tests/dromaeo/cssquery-dojo.html) 13:37:34 INFO - PROCESS | 1780 | RSS: Main: 184729600 13:37:34 INFO - PROCESS | 1780 | 13:37:34 INFO - PROCESS | 1780 | __start_tp_report 13:37:34 INFO - PROCESS | 1780 | _x_x_mozilla_page_load 13:37:34 INFO - PROCESS | 1780 | _x_x_mozilla_page_load_details 13:37:34 INFO - PROCESS | 1780 | |i|pagename|runs| 13:37:34 INFO - PROCESS | 1780 | |0;dojo.html;2721.618973343732;2729.6587926509187;2738.205920283118;2747.3818390862057;2747.560390337546;4645.094206629708;4647.349371088046;4676.1349150407195;4707.952920470794;4726.621870250376;4592.494825569184;4603.976980115095;4640.559146881041;4664;4670.346151538779;3960.603939606031;4147.626713595776;4170.97914510427;4192.434021407117;4204.642605378546;4107.630313271805;4115.42384066231;4145.336746120622;4199.874003779876;4201.663866890642;4096.385542168684;4101.0772576170275;4145;4196.685248606352;4199.3910882921955;4012.1775036117524;4053.635172834459;4116.670666346715;4138.710290279681;4155.958440415588;4005.218982298453;4067.044244602516;4079.673626109904;4150.8547200847825;4164.250434921712;4572.7942242599165;4580.267157254823;4654.5810877020895;4665.603423708989;4677.111348843709;4837.250226214942;4839.322494850724;4845.951540484585;4910.8281210157475;4956.107900577895;4814.229723244299;4819;4891.4863939286215;4901.730404827733;4911.017796440726;4546.363509108727;4620.445546534404;4642.442906851165;4642.883927901829;4645.140648979949;4816.710997340171;4964.90070198594;5063.240513922941;5066.03745288394;5088.313077734532;4737.763111844394;4761.333413322138;4778.116048531033;4792.520747925249;4823.469418363977;4858.101251268456;4877.6341774367065;4952.801887924515;4972.676776009548;4973.8010479581135;4807.471178170398;4834.517585903609;4931.679440836334;4933.851984440473;4946.5053494650965;4820.662553621283;4845.5881250093435;4853.417589889165;4861.392325959255;4942.5304596063315;3235.3529294141267;3330.0509354833757;3331.1006028372203;3331.6002079750583;3334.999500149945;3063;3070.1557071805432;3070.646875609289;3074.477338852356;3076.5385192220897;4569.862904112882;4712.81148754046;4721.5986641135205;4738.810447582127;4746.335513028179;4733.857984260478;4813.78337974792;5000.224965130411;5010.223415370623;5011.248312753116;4426.800793964279;4595.770211489479;4651.3023046543285;4760.45254795696;4774.164521208774;3967.3652215645934;4041.7372870763306;4079.122988557415;4151.086760912594;4164.958350416457;4934.1118598651865;5138.97430512845;5143.177091665316;5156.8968620627375;5189.143791274473;4841.878953026062;4862.4651288357545;4889.9486610378935;4918.336024636628;4930;5048.697078175322;5150.948490515047;5167.328247327823;5174.14626586609;5190.195519694454;4749.596284315872;4845.41854977405;4854.296127061627;4855.635827313036;4855.927161092516;4959.553640172402;4970.005998800182;4973.129702302011;4978.502149784992;4984.252362145708;4787.8563643090765;4825.638077144298;4841.15279826022;4841.273808928548;4849.369581954324;4603.976980115078;4779.689320194177;4828.5412885775795;4850.975745121252;4862;4591.173588754056;4660.953390466188;4695.201815691273;4774.427068751772;4790.137775200497;4660.860174194644;4709.576138147583;4741.857744267678;4794.95205047959;4877.585405240594;4787.0904528139545;4798.064377446504;4799.256115302133;4800.807967681254;4805 13:37:34 INFO - PROCESS | 1780 | |1;ext.html;536.5412572250725;547.0781732780266;554.6505701408113;572.561990077591;594.693732727645;37423;39179.41230881536;40133.598664013385;41849.7907510462;41983;40177;40326.59673403265;40665.593344066554;40712.18575628486;40906.795466022704;6676.899846502306;7794.766157015299;7887.881681774778;7939.523628582304;7949.880751788728;6635.270120286765;9532.714018579454;9843.852342214837;9849.704508864745;10066.748331291703;9863.95068024659;9880.110790028895;9886.110250077492;9922.900770992306;9924.751881202992;6563.081168636395;6764.8308792280095;9597.424154550748;9683.661071862487;9737.172340351079;9398.060193980615;9688.854667179998;9703.66037188695;9762.560684769202;9937.205023598095;36786.448203276974;36878.815605922064;36933;37165.442518362244;37174.62825371739;3064.310530130703;3116.2209447638093;3171.682831716833;3553.4492153715955;3630.2013557017412;27811.443771124465;28291.858540707166;29308.706912931026;29886.804527818862;30259.697403025908;2937.1482270141632;3033.5449682547574;3061.0969763919697;3107.766917481198;3125.671804460522;322136;322276;323944;324003.3799831009;324037;52955.205671914955;52957.47042529602;52988;53148;53183.20225196625;29134.27164320909;29637.407251854842;29733.55399669007;30593.23516912095;30601.08196754101;13731.588052358446;13778.379972901244;13792;13799.2410417426;13809.861901380858;9855.90144098564;9997.500124993721;10022.498875056219;10431.843522347172;10477.266591338534;14867.628309292139;14981.250937453084;17004.914975425043;17284.395046173322;17347.21937512815;6637.336266373421;6774.830629234309;6780.254172041072;6788.355106264897;6817.420519255819;722.5556282885988;767.5509826751413;833.0295206084843;845.2603971524916;911.8905731312152;26252.474950501266;27473.587896181572;27665.585016224773;27727.861360693067;27843;870.7257213977576;927.596495524449;938.7465384346189;977.0913050862624;996.0089710737863;13657.248851313074;14412.711745765235;14628.268586570628;14740.778888316683;14768;494566;539000;542799;646143;709394;50128.749356252985;50659.49340506621;50664.49335506598;50668;50741.492585073676;1016.3952448293254;1045.8744950605824;1121.5121422181378;1124.3872089711126;1135.4663308245113;26314.473710526065;27723.306917327227;28487.572686409305;28610;28682.856585716938;43068.56931430646;43445.56554434415;43856;43883.341749873136;43889.12221755483;387686;391860;394227;396103;402826;23262.53474930458;23718.407039824142;23772.167974120825;24198.274051778473;24828.50342993166;42189;42341.15317693567;42732.35901461543;42876.14247714966;42922.57077429186;34952.65047349596;36199;36485.63514364822;36538.086547836516;37202;36688.26623467569;36893.44659830051;36934;36981;37163.66158113752;33672;34150.487742683385;34596.654033459345;34876.825615871756;34941.301173976884;32094.51858222082;32605.18487037843;32899.835500822344;33190.834045829615;33365.83317083399;23649.881750591136;24965.251042468753;25019.49961000806;25264.747352526974;25382.238532844043 13:37:34 INFO - PROCESS | 1780 | |2;jquery.html;283.7403775545376;299.6958087541145;305.8700052477697;306.8051787115181;314.0656546773348;11998.82001769973;13080.280584567845;16399.180040997948;17029.48911532653;17516.64966700665;16208.189590520456;16873.40943066993;17018.744718829195;17124.74312885308;17162.57093572658;4733.313669517918;5096.4648711885275;5104;5114.974425127888;5149.845504634848;4905.852824415273;5009.198528235483;5066.265391518227;5210.635255532115;5265.447128051557;5126.538611524962;5164.845054648348;5168;5181.274621552986;5191.4289428163065;4893.266010098479;4898.191798353285;5040.697558146487;5162.612804039693;5187.896242075138;5029.974850125763;5033.647644664875;5046.343975283226;5091.541761241506;5119.513646203604;10625.5218515167;11165.38590377529;11395.202335836495;20106;20602.175912963463;1724.7154219553759;1758.3757765993016;1775.7336399540043;1785.6785778559854;1958.2362878477263;8746.912530874673;8844.425112367675;8925.464472131693;8970.237529809974;9029.864552031724;1640.5078476457134;1648.1923857309953;1652.239969613979;1676.8826182167254;1700.6088599622033;80998.59500702459;85344.57327713384;86234.5688271561;86254;86536.567317163;8485.278751306085;8881.289496840367;9046.547672616343;9277.860832087525;9429;9955.651552195639;11003.22977391591;11013.229073964745;11056.502457389275;11075.11399088071;6785.55893866907;6786.69459874297;6871.175458944958;6877.724891004404;7035.401990830836;7060.823479413056;7068.752593659197;7102.3962963148715;7199.460040496879;7236.34872861445;3415.8804441844914;3421.4525675892237;3427.2974040322174;3471.836934626878;3489.2672538766637;1570.5366916759583;1591.9761203581957;1614.1195507234177;1614.459156182692;1636.2146169838545;710.3571268002466;819.9057108432487;850.706506255341;877.4077497689034;892.4511425473416;17297.30810767555;17300.913495432444;17696.91151544234;17930;18018.639627207383;769.54981335918;782.1708988472237;789.423720683904;808.4090489979601;837.995810020946;6581.638009909501;9234.676786312446;9664.371815831948;9749.610015599297;9865.457399843077;353420;353862;367455;370060.1496992552;382651;88669;89277;91532.54233728789;92097.53951230201;95740;809.3323008517996;827.0736774812249;827.3463963468793;829.4857188543021;832.2177153475714;15213.695726085194;15602.84397156014;15788.368465261721;15795.36818527292;15980.28088736033;42123;42250.15499690051;42441;42671.146577067666;44128.11743765171;58951.705241473515;60433.39566604278;60762.696186518784;61903.38096618976;61905;7164.462665300019;7356.705731770669;7389.8152546186775;7461.291177338144;7463.290987356192;9224.677136300466;12688.74622507563;12740.362981850685;12866.292353920624;12877.227366358049;10010.699679009642;10011.849822252527;10062.547185376528;10356.378617282988;10576.576936922746;9963.202943764336;10072.748181295527;10388.896111038794;10594.258401911793;10799.406032667965;10100.949495252476;10111.848322275024;10612.257141999986;10634.574617015232;10704.625338113128;7243.130824300907;7359.742409015659;7367.963160184379;7607.364416650446;7642.312191902755;8286.875696864672;10287.973404016366;10621.097206737513;10637.680869573926;10745.946270268598 13:37:34 INFO - PROCESS | 1780 | |3;mootools.html;1090.3784842639698;1166.5042356998276;1172.0682057764077;1258.0816004316848;1499.6550793317538;5843.795467158645;6402.071699603561;6518.739250429989;6552.705128269227;6554.868902621951;6526.575772574779;6536.052272420497;6544.214694236686;6576.342365763421;6580.506462015356;1777.8399944005005;1795.0396537852253;1798.8381045705885;1800.6848801459691;1806.5303021214513;1768.071762324777;1789.5973405983614;1791.686454870399;1793.7578426912396;1800.2708902894349;1751.3870145449118;1760.7006808842464;1766.814484479124;1771.4951238896917;1773.7871455425302;1712.2380540659412;1733.349993752343;1736.8610511159077;1739.9995002873397;1745.1797655102082;1703.2080082761452;1719.037339090101;1723.3714140137574;1723.9138043097796;1731.9740203896952;6432.614043157379;6460.612363258219;6482.286948435715;6515.087887695679;6520.804375868732;3907.33575292201;4000.8599699010397;4010.2380547695843;4040.8181631826637;4041.211963667086;2682.7451392117914;2794.3433293176085;2827.3779768451113;2874.238326843388;2889.5376739721755;830.622066959538;856;866.4584634603373;875.1336177184605;876.2420506262101;8071.112177660452;8241.587920603946;8330;8349.582520873932;8408.159184081604;3354.6477619849566;3460.8788692395647;3510.9122271943406;3553.7690050146657;3582.6417358263966;7391.260873912674;7403.5928023959195;7411.962940185264;7417.443691723142;7428.182899881009;3865.381538953754;3993.1414745829784;4047.3119569673227;4049.1699201663587;4053.2096241232675;3310.0731795097413;3406;3702.4816525686424;3703.925921481555;3755.6432138946757;2962.6296712910885;3003.984980075086;3018.879244830226;3051.5575241589845;3059.571659967606;976.5263847034183;982.3712823792779;985.2315194148575;995.0348162282575;996.4156990385322;660.9339066093352;668.8929771236578;673.1451057157383;673.9056532085511;675.4427597232303;3707.740458167956;3898.200868821885;3898.434726964574;3903.0242439390154;4011.2178125265877;728.1007955175354;798.7204478432618;813.3330668851603;834.6285902773208;848.702953966107;2603.32313598462;2611.673540807399;2658.3487045673546;2658.3619931216754;2693.7575618194455;7307.19620841707;7594.278543538355;7731.304182623591;7821.452498325063;7909.05091389037;7235.674394652253;7560.659770310349;7566.281203285679;7865.764027079197;7878.330341920886;6052.697365131726;6066.454019138387;6118.1128736332985;6204.162438070893;6252.343503932022;3857.96142038576;3880.301545721797;3918.333883239913;3980.920381592294;3989.401589761559;6923.169219693669;7055.1886533049355;7272.490925635367;7309.122905251404;7358.264173582599;7300.707971681073;7361.742339018108;7453.142888567667;7458.627068646654;7487.925120748723;5282.9471705283995;5650.717464126859;5664.5751568631695;5687.0663974248955;5764;5730.398308177748;5799.101139323411;5803.738831752666;6057.818265452043;6211.844703882439;5551.111822108443;5618.943810561842;5662.518685911744;5714.514266287412;5824.9417505826095;5420.53925416344;5467.562594992471;5599.1041433370465;5646.040173170653;5696.943030569807;4849.175640141114;5008.398992120969;5099.745012749274;5158.200478925773;5162.045021671003;3313.287643156733;3313.486409606515;3365.663433656615;3380.814055226986;3382.763206575516;3480.3561341151967;3493.877714279988;3503.5095086687375;3514.929701406009;3515.9824200879857 13:37:34 INFO - PROCESS | 1780 | |4;prototype.html;240.1821796781958;243.0944730877481;248.44720496894402;249.65048931495903;249.96875390576173;5002.774875130619;5084.542391184793;5132.615053870956;5145.151050076733;5150.562202212812;4818.66269361144;4922.778474968626;4938.678985865916;4992.7753251103695;5047.570956468696;3651.7443778935553;3714.9814250928707;3722.8883133506038;3728.6084961079105;3738.5139931808963;3689.797061161635;3725.8509659613583;3725.88822335329;3733.7386382953205;3734.6825519830845;3567.233044895347;3584.372734771405;3639.5268615080004;3687.539057617798;3701.24124554467;3643.6902863256655;3662.6520480554573;3667.2482141161;3694.907627309325;3704.74066815323;3156.4160630283473;3182.80903145812;3184.1880320518353;3186.4583020886503;3200.439923013487;4835.2505361669;4886.560209581119;4892.877678058041;4899.118158731427;4912.747249451403;4011.197760447922;4214.7681877496725;4279.379489973936;4282.122164956208;4285.121550082225;5142.640015198937;5183.352080989876;5193.376794784613;5196.246544251062;5223.425423203445;3342.331533693247;3357.4795906634267;3387.9661203387896;3393.9490907636405;3397.6262611113007;5399.2980912481125;5443.537299329561;5443.918341224885;5482.369527504348;5495.395506494282;3491.738119641037;3496.790192588402;3500.369933411959;3502.7565214349047;3505.772124811879;3405.6083550391527;3523.4010218262442;3525.4888041233876;3540.6813386794797;3547.450145227494;4058.188362327546;4177.394277829698;4193.161367726467;4207.053412982054;4219.38818871262;5281.366236051698;5293.009449660929;5311.867203319948;5315.521603055666;5330.493603107699;1212.0727643352843;1224.8101544260653;1224.9203801752858;1226.7423840993313;1229.7048708309942;759.081511371247;762.5500954436909;764.8929149919015;778.0507780507771;787.6376866641409;5091.872703182376;5091.974540127276;5098.847034588968;5098.949010509921;5164.1995490699;5401.4868587484125;5407.351117865803;5415.729213539386;5416.214648875891;5423.267858839085;5101.974490127525;5105.15764898795;5107.565856902131;5119.6160287978555;5131.538161565477;2806.424682939992;2842.843643599622;2864.470073036495;2865.5128628133266;2867.899623513167;5612.607117501736;5658.924804287172;5674.914876276859;5695.323198096888;5710.087855193881;5399.757010934517;5424.457554244623;5448.782048718086;5473.343198816167;5474.835754927359;5527.336719593594;5652.773889044557;5698.2592263005545;5711.742971566289;5713.685747283939;5322.707251101072;5335.279737235579;5373.220882972026;5385.73071346439;5386.391117950904;5302.761375738024;5336.2529245904825;5349.866253343697;5353.919691204557;5364.14173732201;5468.152436372368;5717.485426311652;5718.971405142947;5724.370319264961;5727.39862314451;3484.3728128936023;3511.3504001759757;3511.631278715698;3512.3677738007405;3520.7887526748477;5347.679139251657;5395.541378982831;5405.86485337854;5410.431904649955;5414.891702166014;5179.533841954242;5256.9737151314;5266.947330526799;5278.89442211146;5287.656302340336;4982.651214415101;5221.738913054408;5249.7112658804135;5250.816221432232;5261.394939581844;5129.204973229155;5202.635815492879;5203.8178663746585;5204.34945631796;5211.654755360179;5118.616103792156;5217.921731174111;5218.817341393033;5275.947240527699;5295.523402893604;5075.5178258065425;5150.536451719363;5163.199704045879;5222.642113050558;5275.525202731773 13:37:34 INFO - PROCESS | 1780 | |5;yui.html;550.3231025838221;569.2940753465704;627.783414721921;630.3349965786095;636.8216899268203;12697.936510317448;13239.867601323984;13406.865931340695;13413.932930335359;13428.865711342884;10914.23600347976;11045.723856903582;12272.877271227286;12356.320402377865;12466.31435271062;519.714157213533;531.221760121422;533.9733013349337;629.6945981199115;651.3031056769258;528.3949877390386;634.6667999300358;639.3702203329716;653.5621133840315;655.7278729327323;682.7678589279644;684.3088480634545;686.6841253023615;686.797394768542;686.831726227075;678.2234341678775;679.2935347238915;680.880845851974;681.4718593090345;681.4990981628499;395.86144849302804;396.5063495947564;488.30172852820453;495.09397802022215;497.9701217926936;12140.635780926586;12156.574519891761;12208.633740987785;12211.26732396059;12228.877711222864;12563.434645440977;12609.74780504394;12761.361931903368;12922.676933076655;13035.804462933063;12149.939250303692;12255.509779608805;12297.56958506457;12337.259764414164;12352;3985.940210896839;4023.5574086850424;4046.251443482965;4056.127932494528;4064.1058967027207;68738.31261687369;68859.65570172117;68876.31123688749;69135;71949;8548.786280343042;9060.184583387527;9421.434713917188;9662.420254784736;10163.441010744333;17816.821831781515;18004.459866204033;18060.18729157191;18152.183151758203;18177.818221817877;5673.574481913873;5986.461218490357;6081.5742897996715;6089.756409743649;6092.75628974836;3719.75821571597;3793.943090853639;3805.5813860475328;3851.364524853424;3909.179072394772;2533.987330063338;2645.431232285068;2666.9466610668064;2667.1331817159503;2667.7198894116227;478.060610899584;478.42111045634476;480.76442543153604;482.9975850120797;486.9026194760991;523.9921401178985;567.4013915319377;573.8552731033957;591.677535743021;651.6448535548137;8252.473292440918;8320.251177393942;8350.457220280663;8433.03020152678;8472.406931514735;611.9627231842074;632.1623848400887;643.488426700774;674.9257581666013;678.0575000748963;4063.126427817974;4139.151473947833;4159.688023398257;4186.790660466964;4288.871333859989;56740.71629641826;75772.62113689506;77932.61033694795;82054.58972705218;85080.57459712785;73035.63482182554;75189.6240518794;75428;76688.61655691685;76749;56195.43804561984;66458.66770666212;66645;66816.6659166701;67703.64592708217;7993.200679931961;8048.39637027237;8102.392320575863;8261.38039647041;8268.586570671321;43471.565284346754;43691.78154109209;44051;44990;45107.097858043315;44617.33074003957;60347;61261.38738612557;61479.385206147366;61794.69102654458;10268.691939241835;10317.27779055459;10466.947665261625;10742.570297188026;10820;12086.39568021613;12139.271643701406;12216.755664886474;12253.816192757295;12268.570600028956;10344.37933723979;10885.945570272099;11290.548378064785;11360.659180224606;11476;10625.362478251329;10788.244822862323;10841.566337346734;10894.673159804901;11774.52901883915;12561.560345388232;12574.559890403792;12769;13201.537946171838;13253.734925301633;11136.833196425654;11212.83180752273;11378.772424551627;11398.430078496207;11532.423378830857;11190.552377904793;11256.7748645025;11358;11413.543458261576;11420.94289528547 13:37:34 INFO - PROCESS | 1780 | __end_tp_report 13:37:34 INFO - PROCESS | 1780 | __start_cc_report 13:37:34 INFO - PROCESS | 1780 | _x_x_mozilla_cycle_collect,2606 13:37:34 INFO - PROCESS | 1780 | __end_cc_report 13:37:34 INFO - PROCESS | 1780 | __startTimestamp1477946254612__endTimestamp 13:37:34 INFO - PROCESS | 1780 | 13:37:34 INFO - PROCESS | 1780 | ------- Summary: start ------- 13:37:34 INFO - PROCESS | 1780 | Number of tests: 6 13:37:34 INFO - PROCESS | 1780 | 13:37:34 INFO - PROCESS | 1780 | [#0] dojo.html Cycles:165 Average:4535.62 Median:4749.60 stddev:567.68 (12.0%) stddev-sans-first:551.30 13:37:34 INFO - PROCESS | 1780 | Values: 2721.6 2729.7 2738.2 2747.4 2747.6 4645.1 4647.3 4676.1 4708.0 4726.6 4592.5 4604.0 4640.6 4664.0 4670.3 3960.6 4147.6 4171.0 4192.4 4204.6 4107.6 4115.4 4145.3 4199.9 4201.7 4096.4 4101.1 4145.0 4196.7 4199.4 4012.2 4053.6 4116.7 4138.7 4156.0 4005.2 4067.0 4079.7 4150.9 4164.3 4572.8 4580.3 4654.6 4665.6 4677.1 4837.3 4839.3 4846.0 4910.8 4956.1 4814.2 4819.0 4891.5 4901.7 4911.0 4546.4 4620.4 4642.4 4642.9 4645.1 4816.7 4964.9 5063.2 5066.0 5088.3 4737.8 4761.3 4778.1 4792.5 4823.5 4858.1 4877.6 4952.8 4972.7 4973.8 4807.5 4834.5 4931.7 4933.9 4946.5 4820.7 4845.6 4853.4 4861.4 4942.5 3235.4 3330.1 3331.1 3331.6 3335.0 3063.0 3070.2 3070.6 3074.5 3076.5 4569.9 4712.8 4721.6 4738.8 4746.3 4733.9 4813.8 5000.2 5010.2 5011.2 4426.8 4595.8 4651.3 4760.5 4774.2 3967.4 4041.7 4079.1 4151.1 4165.0 4934.1 5139.0 5143.2 5156.9 5189.1 4841.9 4862.5 4889.9 4918.3 4930.0 5048.7 5150.9 5167.3 5174.1 5190.2 4749.6 4845.4 4854.3 4855.6 4855.9 4959.6 4970.0 4973.1 4978.5 4984.3 4787.9 4825.6 4841.2 4841.3 4849.4 4604.0 4779.7 4828.5 4851.0 4862.0 4591.2 4661.0 4695.2 4774.4 4790.1 4660.9 4709.6 4741.9 4795.0 4877.6 4787.1 4798.1 4799.3 4800.8 4805.0 13:37:34 INFO - PROCESS | 1780 | 13:37:34 INFO - PROCESS | 1780 | [#1] ext.html Cycles:180 Average:56431.36 Median:37164.55 stddev:120524.24 (324.3%) stddev-sans-first:120789.28 13:37:34 INFO - PROCESS | 1780 | Values: 536.5 547.1 554.7 572.6 594.7 37423.0 39179.4 40133.6 41849.8 41983.0 40177.0 40326.6 40665.6 40712.2 40906.8 6676.9 7794.8 7887.9 7939.5 7949.9 6635.3 9532.7 9843.9 9849.7 10066.7 9864.0 9880.1 9886.1 9922.9 9924.8 6563.1 6764.8 9597.4 9683.7 9737.2 9398.1 9688.9 9703.7 9762.6 9937.2 36786.4 36878.8 36933.0 37165.4 37174.6 3064.3 3116.2 3171.7 3553.4 3630.2 27811.4 28291.9 29308.7 29886.8 30259.7 2937.1 3033.5 3061.1 3107.8 3125.7 322136.0 322276.0 323944.0 324003.4 324037.0 52955.2 52957.5 52988.0 53148.0 53183.2 29134.3 29637.4 29733.6 30593.2 30601.1 13731.6 13778.4 13792.0 13799.2 13809.9 9855.9 9997.5 10022.5 10431.8 10477.3 14867.6 14981.3 17004.9 17284.4 17347.2 6637.3 6774.8 6780.3 6788.4 6817.4 722.6 767.6 833.0 845.3 911.9 26252.5 27473.6 27665.6 27727.9 27843.0 870.7 927.6 938.7 977.1 996.0 13657.2 14412.7 14628.3 14740.8 14768.0 494566.0 539000.0 542799.0 646143.0 709394.0 50128.7 50659.5 50664.5 50668.0 50741.5 1016.4 1045.9 1121.5 1124.4 1135.5 26314.5 27723.3 28487.6 28610.0 28682.9 43068.6 43445.6 43856.0 43883.3 43889.1 387686.0 391860.0 394227.0 396103.0 402826.0 23262.5 23718.4 23772.2 24198.3 24828.5 42189.0 42341.2 42732.4 42876.1 42922.6 34952.7 36199.0 36485.6 36538.1 37202.0 36688.3 36893.4 36934.0 36981.0 37163.7 33672.0 34150.5 34596.7 34876.8 34941.3 32094.5 32605.2 32899.8 33190.8 33365.8 23649.9 24965.3 25019.5 25264.7 25382.2 13:37:34 INFO - PROCESS | 1780 | 13:37:34 INFO - PROCESS | 1780 | [#2] jquery.html Cycles:180 Average:24504.82 Median:5019.59 stddev:61596.83 (1227.1%) stddev-sans-first:61742.78 13:37:34 INFO - PROCESS | 1780 | Values: 283.7 299.7 305.9 306.8 314.1 11998.8 13080.3 16399.2 17029.5 17516.6 16208.2 16873.4 17018.7 17124.7 17162.6 4733.3 5096.5 5104.0 5115.0 5149.8 4905.9 5009.2 5066.3 5210.6 5265.4 5126.5 5164.8 5168.0 5181.3 5191.4 4893.3 4898.2 5040.7 5162.6 5187.9 5030.0 5033.6 5046.3 5091.5 5119.5 10625.5 11165.4 11395.2 20106.0 20602.2 1724.7 1758.4 1775.7 1785.7 1958.2 8746.9 8844.4 8925.5 8970.2 9029.9 1640.5 1648.2 1652.2 1676.9 1700.6 80998.6 85344.6 86234.6 86254.0 86536.6 8485.3 8881.3 9046.5 9277.9 9429.0 9955.7 11003.2 11013.2 11056.5 11075.1 6785.6 6786.7 6871.2 6877.7 7035.4 7060.8 7068.8 7102.4 7199.5 7236.3 3415.9 3421.5 3427.3 3471.8 3489.3 1570.5 1592.0 1614.1 1614.5 1636.2 710.4 819.9 850.7 877.4 892.5 17297.3 17300.9 17696.9 17930.0 18018.6 769.5 782.2 789.4 808.4 838.0 6581.6 9234.7 9664.4 9749.6 9865.5 353420.0 353862.0 367455.0 370060.1 382651.0 88669.0 89277.0 91532.5 92097.5 95740.0 809.3 827.1 827.3 829.5 832.2 15213.7 15602.8 15788.4 15795.4 15980.3 42123.0 42250.2 42441.0 42671.1 44128.1 58951.7 60433.4 60762.7 61903.4 61905.0 7164.5 7356.7 7389.8 7461.3 7463.3 9224.7 12688.7 12740.4 12866.3 12877.2 10010.7 10011.8 10062.5 10356.4 10576.6 9963.2 10072.7 10388.9 10594.3 10799.4 10100.9 10111.8 10612.3 10634.6 10704.6 7243.1 7359.7 7368.0 7607.4 7642.3 8286.9 10288.0 10621.1 10637.7 10745.9 13:37:34 INFO - PROCESS | 1780 | 13:37:34 INFO - PROCESS | 1780 | [#3] mootools.html Cycles:180 Average:4181.36 Median:5351.74 stddev:2312.06 (43.2%) stddev-sans-first:2306.88 13:37:34 INFO - PROCESS | 1780 | Values: 1090.4 1166.5 1172.1 1258.1 1499.7 5843.8 6402.1 6518.7 6552.7 6554.9 6526.6 6536.1 6544.2 6576.3 6580.5 1777.8 1795.0 1798.8 1800.7 1806.5 1768.1 1789.6 1791.7 1793.8 1800.3 1751.4 1760.7 1766.8 1771.5 1773.8 1712.2 1733.3 1736.9 1740.0 1745.2 1703.2 1719.0 1723.4 1723.9 1732.0 6432.6 6460.6 6482.3 6515.1 6520.8 3907.3 4000.9 4010.2 4040.8 4041.2 2682.7 2794.3 2827.4 2874.2 2889.5 830.6 856.0 866.5 875.1 876.2 8071.1 8241.6 8330.0 8349.6 8408.2 3354.6 3460.9 3510.9 3553.8 3582.6 7391.3 7403.6 7412.0 7417.4 7428.2 3865.4 3993.1 4047.3 4049.2 4053.2 3310.1 3406.0 3702.5 3703.9 3755.6 2962.6 3004.0 3018.9 3051.6 3059.6 976.5 982.4 985.2 995.0 996.4 660.9 668.9 673.1 673.9 675.4 3707.7 3898.2 3898.4 3903.0 4011.2 728.1 798.7 813.3 834.6 848.7 2603.3 2611.7 2658.3 2658.4 2693.8 7307.2 7594.3 7731.3 7821.5 7909.1 7235.7 7560.7 7566.3 7865.8 7878.3 6052.7 6066.5 6118.1 6204.2 6252.3 3858.0 3880.3 3918.3 3980.9 3989.4 6923.2 7055.2 7272.5 7309.1 7358.3 7300.7 7361.7 7453.1 7458.6 7487.9 5282.9 5650.7 5664.6 5687.1 5764.0 5730.4 5799.1 5803.7 6057.8 6211.8 5551.1 5618.9 5662.5 5714.5 5824.9 5420.5 5467.6 5599.1 5646.0 5696.9 4849.2 5008.4 5099.7 5158.2 5162.0 3313.3 3313.5 3365.7 3380.8 3382.8 3480.4 3493.9 3503.5 3514.9 3516.0 13:37:34 INFO - PROCESS | 1780 | 13:37:34 INFO - PROCESS | 1780 | [#4] prototype.html Cycles:180 Average:4344.23 Median:5113.09 stddev:1373.55 (26.9%) stddev-sans-first:1342.42 13:37:34 INFO - PROCESS | 1780 | Values: 240.2 243.1 248.4 249.7 250.0 5002.8 5084.5 5132.6 5145.2 5150.6 4818.7 4922.8 4938.7 4992.8 5047.6 3651.7 3715.0 3722.9 3728.6 3738.5 3689.8 3725.9 3725.9 3733.7 3734.7 3567.2 3584.4 3639.5 3687.5 3701.2 3643.7 3662.7 3667.2 3694.9 3704.7 3156.4 3182.8 3184.2 3186.5 3200.4 4835.3 4886.6 4892.9 4899.1 4912.7 4011.2 4214.8 4279.4 4282.1 4285.1 5142.6 5183.4 5193.4 5196.2 5223.4 3342.3 3357.5 3388.0 3393.9 3397.6 5399.3 5443.5 5443.9 5482.4 5495.4 3491.7 3496.8 3500.4 3502.8 3505.8 3405.6 3523.4 3525.5 3540.7 3547.5 4058.2 4177.4 4193.2 4207.1 4219.4 5281.4 5293.0 5311.9 5315.5 5330.5 1212.1 1224.8 1224.9 1226.7 1229.7 759.1 762.6 764.9 778.1 787.6 5091.9 5092.0 5098.8 5098.9 5164.2 5401.5 5407.4 5415.7 5416.2 5423.3 5102.0 5105.2 5107.6 5119.6 5131.5 2806.4 2842.8 2864.5 2865.5 2867.9 5612.6 5658.9 5674.9 5695.3 5710.1 5399.8 5424.5 5448.8 5473.3 5474.8 5527.3 5652.8 5698.3 5711.7 5713.7 5322.7 5335.3 5373.2 5385.7 5386.4 5302.8 5336.3 5349.9 5353.9 5364.1 5468.2 5717.5 5719.0 5724.4 5727.4 3484.4 3511.4 3511.6 3512.4 3520.8 5347.7 5395.5 5405.9 5410.4 5414.9 5179.5 5257.0 5266.9 5278.9 5287.7 4982.7 5221.7 5249.7 5250.8 5261.4 5129.2 5202.6 5203.8 5204.3 5211.7 5118.6 5217.9 5218.8 5275.9 5295.5 5075.5 5150.5 5163.2 5222.6 5275.5 13:37:34 INFO - PROCESS | 1780 | 13:37:34 INFO - PROCESS | 1780 | [#5] yui.html Cycles:180 Average:16662.65 Median:44334.17 stddev:22575.75 (50.9%) stddev-sans-first:22606.66 13:37:34 INFO - PROCESS | 1780 | Values: 550.3 569.3 627.8 630.3 636.8 12697.9 13239.9 13406.9 13413.9 13428.9 10914.2 11045.7 12272.9 12356.3 12466.3 519.7 531.2 534.0 629.7 651.3 528.4 634.7 639.4 653.6 655.7 682.8 684.3 686.7 686.8 686.8 678.2 679.3 680.9 681.5 681.5 395.9 396.5 488.3 495.1 498.0 12140.6 12156.6 12208.6 12211.3 12228.9 12563.4 12609.7 12761.4 12922.7 13035.8 12149.9 12255.5 12297.6 12337.3 12352.0 3985.9 4023.6 4046.3 4056.1 4064.1 68738.3 68859.7 68876.3 69135.0 71949.0 8548.8 9060.2 9421.4 9662.4 10163.4 17816.8 18004.5 18060.2 18152.2 18177.8 5673.6 5986.5 6081.6 6089.8 6092.8 3719.8 3793.9 3805.6 3851.4 3909.2 2534.0 2645.4 2666.9 2667.1 2667.7 478.1 478.4 480.8 483.0 486.9 524.0 567.4 573.9 591.7 651.6 8252.5 8320.3 8350.5 8433.0 8472.4 612.0 632.2 643.5 674.9 678.1 4063.1 4139.2 4159.7 4186.8 4288.9 56740.7 75772.6 77932.6 82054.6 85080.6 73035.6 75189.6 75428.0 76688.6 76749.0 56195.4 66458.7 66645.0 66816.7 67703.6 7993.2 8048.4 8102.4 8261.4 8268.6 43471.6 43691.8 44051.0 44990.0 45107.1 44617.3 60347.0 61261.4 61479.4 61794.7 10268.7 10317.3 10466.9 10742.6 10820.0 12086.4 12139.3 12216.8 12253.8 12268.6 10344.4 10885.9 11290.5 11360.7 11476.0 10625.4 10788.2 10841.6 10894.7 11774.5 12561.6 12574.6 12769.0 13201.5 13253.7 11136.8 11212.8 11378.8 11398.4 11532.4 11190.6 11256.8 11358.0 11413.5 11420.9 13:37:34 INFO - PROCESS | 1780 | -------- Summary: end -------- 13:37:34 INFO - PROCESS | 1780 | 13:37:35 INFO - TEST-INFO | 1780: exit 0 13:37:35 INFO - TEST-OK | dromaeo_css | took 1121045ms 13:37:35 INFO - TEST-START | kraken 13:37:35 INFO - operating with platform_type : w7_ 13:37:35 INFO - Initialising browser for kraken test... 13:37:35 INFO - TEST-INFO | started process 3732 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpm1tfy6\profile http://localhost:49668/getInfo.html) 13:37:40 INFO - PROCESS | 3732 | __metrics Screen width/height:1600/1200 13:37:40 INFO - PROCESS | 3732 | colorDepth:24 13:37:40 INFO - PROCESS | 3732 | Browser inner width/height: 1010/674 13:37:40 INFO - PROCESS | 3732 | __metrics 13:37:41 INFO - PROCESS | 3732 | 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" 13:37:41 INFO - PROCESS | 3732 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:37:41 INFO - PROCESS | 3732 | console.error: 13:37:41 INFO - PROCESS | 3732 | Message: Error: Connection closed before committing the transaction. 13:37:41 INFO - PROCESS | 3732 | Stack: 13:37:41 INFO - PROCESS | 3732 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:937:23 13:37:41 INFO - PROCESS | 3732 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 13:37:41 INFO - PROCESS | 3732 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 13:37:41 INFO - PROCESS | 3732 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 13:37:41 INFO - PROCESS | 3732 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 13:37:41 INFO - PROCESS | 3732 | TaskImpl_run@resource://gre/modules/Task.jsm:327:15 13:37:41 INFO - PROCESS | 3732 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9 13:37:41 INFO - PROCESS | 3732 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 13:37:41 INFO - PROCESS | 3732 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5 13:37:41 INFO - PROCESS | 3732 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 13:37:41 INFO - PROCESS | 3732 | Barrier/this.client.addBlocker/promise13.1% 13:38:38 INFO - TinderboxPrint: I/O read bytes / time
147,318,272 / 1,324,460 13:38:38 INFO - TinderboxPrint: I/O write bytes / time
465,245,696 / 3,676,580 13:38:38 INFO - TinderboxPrint: CPU idle
8,258.2 (86.9%) 13:38:38 INFO - TinderboxPrint: CPU user
1,212.5 (12.8%) 13:38:38 INFO - install - Wall time: 3s; CPU: 10.0%; Read bytes: 0; Write bytes: 34002432; Read time: 0; Write time: 55600 13:38:38 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 147318272; Write bytes: 392641024; Read time: 1324460; Write time: 3292940 13:38:39 INFO - Running post-run listener: _upload_blobber_files 13:38:39 INFO - Blob upload gear active. 13:38:39 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir. 13:38: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 13:38: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', 'Firefox', '-d', 'C:\\slave\\test\\build\\blobber_upload_dir', '--output-manifest', 'C:\\slave\\test\\build\\uploaded_files.json'] 13:38: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 Firefox -d C:\slave\test\build\blobber_upload_dir --output-manifest C:\slave\test\build\uploaded_files.json 13:38:40 INFO - (blobuploader) - INFO - Open directory for files ... 13:38:40 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log ... 13:38:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:38:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:38:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:38:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:38:40 INFO - (blobuploader) - INFO - Done attempting. 13:38:40 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log ... 13:38:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:38:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:38:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:38:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:38:41 INFO - (blobuploader) - INFO - Done attempting. 13:38:41 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ... 13:38:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:38:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:38:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:38:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:38:49 INFO - (blobuploader) - INFO - Done attempting. 13:38:49 INFO - (blobuploader) - INFO - Iteration through files over. 13:38:49 INFO - Return code: 0 13:38:49 INFO - rmtree: C:\slave\test\build\uploaded_files.json 13:38:49 INFO - Using _rmtree_windows ... 13:38:49 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1 13:38:49 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fb92afbbe384b24d9c875d033488996cb0b747ec67e362622ab8d38da2e4941c1075a7da65c6513baf2550ce62d8e212bd0af0d26914e44eea23e8cca56b7126", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c74660076b2ce9f786a5a1fdf9a352dd20039822e8d86a38909366422e97998a1e92e238aedaafabae70237b7b64c229ff89a8a52654a1e1ecbf7851228aaab6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2adbd2350e50af1531d4fe4b16024643623871c753036a02a37bf00d370b580fe08761f982a65f5e2b1fd4d5602a0f0a8cb4e5694bdede56ba8eb1d7131c780a"} 13:38:49 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files 13:38:49 INFO - Writing to file C:\slave\test\properties\blobber_files 13:38:49 INFO - Contents: 13:38:49 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fb92afbbe384b24d9c875d033488996cb0b747ec67e362622ab8d38da2e4941c1075a7da65c6513baf2550ce62d8e212bd0af0d26914e44eea23e8cca56b7126", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c74660076b2ce9f786a5a1fdf9a352dd20039822e8d86a38909366422e97998a1e92e238aedaafabae70237b7b64c229ff89a8a52654a1e1ecbf7851228aaab6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2adbd2350e50af1531d4fe4b16024643623871c753036a02a37bf00d370b580fe08761f982a65f5e2b1fd4d5602a0f0a8cb4e5694bdede56ba8eb1d7131c780a"} 13:38:49 INFO - Running post-run listener: copy_logs_to_upload_dir 13:38:49 INFO - Copying logs to upload dir... 13:38:49 INFO - mkdir: C:\slave\test\build\upload\logs 13:38:49 INFO - Copying logs to upload dir... 13:38:49 INFO - Using _rmtree_windows ... 13:38:49 INFO - Using _rmtree_windows ... 13:38:49 INFO - Using _rmtree_windows ... 13:38:49 INFO - Using _rmtree_windows ... 13:38:49 INFO - Using _rmtree_windows ... 13:38:49 INFO - Using _rmtree_windows ... 13:38:49 INFO - Using _rmtree_windows ... program finished with exit code 0 elapsedTime=1490.180000 ========= master_lag: 1.36 ========= ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 51 secs) (at 2016-10-31 13:38:49.493296) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-31 13:38:49.498935) ========= '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-031 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-031 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-031 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/Firefox/sha512/fb92afbbe384b24d9c875d033488996cb0b747ec67e362622ab8d38da2e4941c1075a7da65c6513baf2550ce62d8e212bd0af0d26914e44eea23e8cca56b7126", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c74660076b2ce9f786a5a1fdf9a352dd20039822e8d86a38909366422e97998a1e92e238aedaafabae70237b7b64c229ff89a8a52654a1e1ecbf7851228aaab6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2adbd2350e50af1531d4fe4b16024643623871c753036a02a37bf00d370b580fe08761f982a65f5e2b1fd4d5602a0f0a8cb4e5694bdede56ba8eb1d7131c780a"} build_url:https://queue.taskcluster.net/v1/task/IGvUpc-MTVW6MYT8SS5gxw/artifacts/public/build/firefox-52.0a1.en-US.win32.zip program finished with exit code 0 elapsedTime=0.101000 build_url: 'https://queue.taskcluster.net/v1/task/IGvUpc-MTVW6MYT8SS5gxw/artifacts/public/build/firefox-52.0a1.en-US.win32.zip' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fb92afbbe384b24d9c875d033488996cb0b747ec67e362622ab8d38da2e4941c1075a7da65c6513baf2550ce62d8e212bd0af0d26914e44eea23e8cca56b7126", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c74660076b2ce9f786a5a1fdf9a352dd20039822e8d86a38909366422e97998a1e92e238aedaafabae70237b7b64c229ff89a8a52654a1e1ecbf7851228aaab6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2adbd2350e50af1531d4fe4b16024643623871c753036a02a37bf00d370b580fe08761f982a65f5e2b1fd4d5602a0f0a8cb4e5694bdede56ba8eb1d7131c780a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-31 13:38:49.616564) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 13:38:49.616885) ========= '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-031 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-031 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-031 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-31 13:38:49.728014) ========= ========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-10-31 13:38:49.728309) ========= ========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-10-31 13:38:50.266985) ========= ========= Total master_lag: 1.67 =========