builder: mozilla-central_win7_ix_test-dromaeojs-pgo slave: t-w732-ix-034 starttime: 1476638066.98 results: success (0) buildid: 20161016073001 builduid: 52ab78f8c15342c38a89ce65c3130b98 revision: 9079d167112122805f99f57bb8856e1b1675af0f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-16 10:14:26.975879) ========= master: http://buildbot-master110.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-16 10:14:26.976333) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-16 10:14:26.976618) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-16 10:14:27.067356) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-16 10:14:27.067661) ========= '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-034 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-034 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-034 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 --10:14:27-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py => `archiver_client.py' Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. WARNING: Certificate verification error for hg.mozilla.org: certificate signature failure HTTP request sent, awaiting response... 200 Script output follows Length: 12,179 (12K) [text/x-python] 0K .......... . 100% 2.27 GB/s 10:14:28 (2.27 GB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=1.167000 ========= master_lag: 0.08 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-16 10:14:28.309966) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 10 secs) (at 2016-10-16 10:14:28.310274) ========= '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-034 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-034 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-034 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=10.301000 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 10 secs) (at 2016-10-16 10:14:38.623128) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-16 10:14:38.623733) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo mozilla-central --rev 9079d167112122805f99f57bb8856e1b1675af0f --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 9079d167112122805f99f57bb8856e1b1675af0f --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-034 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-034 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-034 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-16 10:14:38,852 truncating revision to first 12 chars 2016-10-16 10:14:38,852 Setting DEBUG logging. 2016-10-16 10:14:38,852 attempt 1/10 2016-10-16 10:14:38,852 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/9079d1671121?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-16 10:14:40,302 unpacking tar archive at: mozilla-central-9079d1671121/testing/mozharness/ 2016-10-16 10:14:40,424 skipping symlink on windows: configs/single_locale/linux32.py program finished with exit code 0 elapsedTime=2.068000 ========= master_lag: 0.07 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-16 10:14:40.765330) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-16 10:14:40.765677) ========= script_repo_revision: 9079d167112122805f99f57bb8856e1b1675af0f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-16 10:14:40.766146) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-16 10:14:40.766488) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-16 10:14:40.777442) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 25 secs) (at 2016-10-16 10:14:40.777890) ========= '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-034 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-034 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-034 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 10:14:41 INFO - MultiFileLogger online at 20161016 10:14:41 in C:\slave\test 10:14:41 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 10:14:41 INFO - Dumping config to C:\slave\test\logs\localconfig.json. 10:14:41 INFO - {'append_to_log': False, 10:14:41 INFO - 'base_work_dir': 'C:\\slave\\test', 10:14:41 INFO - 'blob_upload_branch': 'Firefox', 10:14:41 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt', 10:14:41 INFO - 'branch': 'Firefox', 10:14:41 INFO - 'buildbot_json_path': 'buildprops.json', 10:14:41 INFO - 'config_files': ('talos/windows_config.py',), 10:14:41 INFO - 'default_actions': ('clobber', 10:14:41 INFO - 'read-buildbot-config', 10:14:41 INFO - 'download-and-extract', 10:14:41 INFO - 'populate-webroot', 10:14:41 INFO - 'create-virtualenv', 10:14:41 INFO - 'install', 10:14:41 INFO - 'run-tests'), 10:14:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:14:41 INFO - 'download_minidump_stackwalk': True, 10:14:41 INFO - 'download_symbols': 'ondemand', 10:14:41 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python', 10:14:41 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'), 10:14:41 INFO - 'hg': 'c:/mozilla-build/hg/hg', 10:14:41 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python', 10:14:41 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'), 10:14:41 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 10:14:41 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 10:14:41 INFO - 'C:/mozilla-build/tooltool.py'), 10:14:41 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 10:14:41 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 10:14:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:14:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:14:41 INFO - 'installer_path': 'installer.exe', 10:14:41 INFO - 'log_level': 'info', 10:14:41 INFO - 'log_name': 'talos', 10:14:41 INFO - 'log_to_console': True, 10:14:41 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 10:14:41 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 10:14:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 10:14:41 INFO - 'opt_config_files': (), 10:14:41 INFO - 'pip_index': False, 10:14:41 INFO - 'sps_profile': False, 10:14:41 INFO - 'sps_profile_interval': 0, 10:14:41 INFO - 'suite': 'dromaeojs', 10:14:41 INFO - 'system_bits': '32', 10:14:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:14:41 INFO - 'title': 'T-W732-IX-034', 10:14:41 INFO - 'use_talos_json': True, 10:14:41 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 10:14:41 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv', 10:14:41 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 10:14:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:14:41 INFO - 'work_dir': 'build'} 10:14:41 INFO - [mozharness: 2016-10-16 17:14:41.182000Z] Running clobber step. 10:14:41 INFO - Running pre-action listener: _resource_record_pre_action 10:14:41 INFO - Running main action method: clobber 10:14:41 INFO - rmtree: C:\slave\test\build 10:14:41 INFO - Using _rmtree_windows ... 10:14:41 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1 10:17:12 INFO - Running post-action listener: _resource_record_post_action 10:17:12 INFO - [mozharness: 2016-10-16 17:17:12.490000Z] Finished clobber step (success) 10:17:12 INFO - [mozharness: 2016-10-16 17:17:12.490000Z] Running read-buildbot-config step. 10:17:12 INFO - Running pre-action listener: _resource_record_pre_action 10:17:12 INFO - Running main action method: read_buildbot_config 10:17:12 INFO - Using buildbot properties: 10:17:12 INFO - { 10:17:12 INFO - "project": "", 10:17:12 INFO - "product": "firefox", 10:17:12 INFO - "script_repo_revision": "production", 10:17:12 INFO - "builddir": "mozilla-central_win7_ix_test-dromaeojs", 10:17:12 INFO - "repository": "", 10:17:12 INFO - "buildername": "Windows 7 32-bit mozilla-central pgo talos dromaeojs", 10:17:12 INFO - "buildid": "20161016073001", 10:17:12 INFO - "pgo_build": "True", 10:17:12 INFO - "basedir": "C:\\slave\\test", 10:17:12 INFO - "buildnumber": 54, 10:17:12 INFO - "slavename": "t-w732-ix-034", 10:17:12 INFO - "master": "http://buildbot-master110.bb.releng.scl3.mozilla.com:8201/", 10:17:12 INFO - "platform": "win7_ix", 10:17:12 INFO - "scheduler": "tests-mozilla-central-win32-pgo-talos", 10:17:12 INFO - "branch": "mozilla-central", 10:17:12 INFO - "repo_path": "mozilla-central", 10:17:12 INFO - "revision": "9079d167112122805f99f57bb8856e1b1675af0f", 10:17:12 INFO - "stage_platform": "win32-pgo", 10:17:12 INFO - "builduid": "52ab78f8c15342c38a89ce65c3130b98", 10:17:12 INFO - "slavebuilddir": "test" 10:17:12 INFO - } 10:17:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/XujZSCqOTiqiqHTzLR6aPQ/artifacts/public/build/firefox-52.0a1.en-US.win32.zip. 10:17:12 INFO - Running post-action listener: _resource_record_post_action 10:17:12 INFO - [mozharness: 2016-10-16 17:17:12.494000Z] Finished read-buildbot-config step (success) 10:17:12 INFO - [mozharness: 2016-10-16 17:17:12.494000Z] Running download-and-extract step. 10:17:12 INFO - Running pre-action listener: _resource_record_pre_action 10:17:12 INFO - Running main action method: download_and_extract 10:17:12 INFO - mkdir: C:\slave\test\build\tests 10:17: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')]} 10:17:12 INFO - trying https://queue.taskcluster.net/v1/task/XujZSCqOTiqiqHTzLR6aPQ/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json 10:17:12 INFO - Downloading https://queue.taskcluster.net/v1/task/XujZSCqOTiqiqHTzLR6aPQ/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 10:17:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XujZSCqOTiqiqHTzLR6aPQ/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 10:17:13 INFO - Downloaded 1218 bytes. 10:17:13 INFO - Reading from file C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json 10:17:13 INFO - Using the following test package requirements: 10:17:13 INFO - {u'common': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 10:17:13 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 10:17:13 INFO - u'firefox-52.0a1.en-US.win32.cppunittest.tests.zip'], 10:17:13 INFO - u'gtest': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 10:17:13 INFO - u'jittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 10:17:13 INFO - u'jsshell-win32.zip'], 10:17:13 INFO - u'mochitest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 10:17:13 INFO - u'firefox-52.0a1.en-US.win32.mochitest.tests.zip'], 10:17:13 INFO - u'mozbase': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 10:17:13 INFO - u'reftest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 10:17:13 INFO - u'firefox-52.0a1.en-US.win32.reftest.tests.zip'], 10:17:13 INFO - u'talos': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 10:17:13 INFO - u'firefox-52.0a1.en-US.win32.talos.tests.zip'], 10:17:13 INFO - u'web-platform': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 10:17:13 INFO - u'firefox-52.0a1.en-US.win32.web-platform.tests.zip'], 10:17:13 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 10:17:13 INFO - u'firefox-52.0a1.en-US.win32.xpcshell.tests.zip']} 10:17:13 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip'] for test suite category: common 10:17:13 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/XujZSCqOTiqiqHTzLR6aPQ/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip 10:17:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XujZSCqOTiqiqHTzLR6aPQ/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1 10:17:13 INFO - Fetch https://queue.taskcluster.net/v1/task/XujZSCqOTiqiqHTzLR6aPQ/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory 10:17:14 INFO - Http code: 200 10:17:14 INFO - via: 1.1 a75342fa94ff4a9431476624fe74f02e.cloudfront.net (CloudFront) 10:17:14 INFO - x-amz-cf-id: PoHv98Bck-w_O1G7ZrjsSL19b928LpMSMSzUhbsF1AJAPwOz68zE4w== 10:17:14 INFO - x-amz-version-id: .94AcXWhtL5.pOmIPvXHY2aZxSLebfgQ 10:17:14 INFO - Expected file size: 17274992 10:17:14 INFO - Obtained file size: 17274992 10:17:50 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 10:17:50 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/XujZSCqOTiqiqHTzLR6aPQ/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip 10:17:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XujZSCqOTiqiqHTzLR6aPQ/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1 10:17:50 INFO - Fetch https://queue.taskcluster.net/v1/task/XujZSCqOTiqiqHTzLR6aPQ/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory 10:17:51 INFO - Http code: 200 10:17:51 INFO - via: 1.1 73fa5a1ee49cf827e596b502b5927eab.cloudfront.net (CloudFront) 10:17:51 INFO - x-amz-cf-id: sLipYzftCeot9l75iOJk5ufOx7KQKE47q_iP-kURkpjcYLQJL4zTiA== 10:17:51 INFO - x-amz-version-id: .94AcXWhtL5.pOmIPvXHY2aZxSLebfgQ 10:17:51 INFO - Expected file size: 17274992 10:17:51 INFO - Obtained file size: 17274992 10:18:04 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/XujZSCqOTiqiqHTzLR6aPQ/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip 10:18:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XujZSCqOTiqiqHTzLR6aPQ/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip'}, attempt #1 10:18:04 INFO - Fetch https://queue.taskcluster.net/v1/task/XujZSCqOTiqiqHTzLR6aPQ/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip into memory 10:18:04 INFO - Http code: 200 10:18:04 INFO - via: 1.1 8008015354a3ca72f56c382a1d1cfe9f.cloudfront.net (CloudFront) 10:18:04 INFO - x-amz-cf-id: QmMboPswxroiMDAn8fsu09VLl21AD8_uK7waDs3dbmXsxeBwKXUkKg== 10:18:04 INFO - x-amz-version-id: QlK4e8C2Ep78s.oIksALHsgF666UYN1U 10:18:05 INFO - Expected file size: 14409028 10:18:05 INFO - Obtained file size: 14409028 10:18:05 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')]} 10:18:05 INFO - trying https://queue.taskcluster.net/v1/task/XujZSCqOTiqiqHTzLR6aPQ/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 10:18:05 INFO - Downloading https://queue.taskcluster.net/v1/task/XujZSCqOTiqiqHTzLR6aPQ/artifacts/public/build/firefox-52.0a1.en-US.win32.zip to C:\slave\test\build\firefox-52.0a1.en-US.win32.zip 10:18:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XujZSCqOTiqiqHTzLR6aPQ/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 10:18:07 INFO - Downloaded 54303144 bytes. 10:18:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XujZSCqOTiqiqHTzLR6aPQ/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 10:18:07 INFO - mkdir: C:\slave\test\properties 10:18:07 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url 10:18:07 INFO - Writing to file C:\slave\test\properties\build_url 10:18:07 INFO - Contents: 10:18:07 INFO - build_url:https://queue.taskcluster.net/v1/task/XujZSCqOTiqiqHTzLR6aPQ/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 10:18:07 INFO - Running post-action listener: _resource_record_post_action 10:18:07 INFO - Running post-action listener: set_extra_try_arguments 10:18:07 INFO - [mozharness: 2016-10-16 17:18:07.867000Z] Finished download-and-extract step (success) 10:18:07 INFO - [mozharness: 2016-10-16 17:18:07.867000Z] Running populate-webroot step. 10:18:07 INFO - Running pre-action listener: _resource_record_pre_action 10:18:07 INFO - Running main action method: populate_webroot 10:18:07 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 10:18:07 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 10:18:07 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 10:18:07 INFO - u'--tppagecycles', 10:18:07 INFO - u'7'], 10:18:07 INFO - u'tests': [u'tsvgm']}}, 10:18:07 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'], 10:18:07 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 10:18:07 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']}, 10:18:07 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'], 10:18:07 INFO - u'tests': [u'dromaeo_css', u'kraken']}, 10:18:07 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']}, 10:18:07 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:18:07 INFO - u'talos_options': [u'--disable-e10s'], 10:18:07 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 10:18:07 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:18:07 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 10:18:07 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:18:07 INFO - u'talos_options': [u'--disable-e10s'], 10:18:07 INFO - u'tests': [u'damp', u'tps']}, 10:18:07 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:18:07 INFO - u'tests': [u'damp', u'tps']}, 10:18:07 INFO - u'g3': {u'talos_options': [u'--disable-e10s'], 10:18:07 INFO - u'tests': [u'dromaeo_dom']}, 10:18:07 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 10:18:07 INFO - u'g4': {u'talos_options': [u'--disable-e10s'], 10:18:07 INFO - u'tests': [u'basic_compositor_video']}, 10:18:07 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']}, 10:18:07 INFO - u'other': {u'talos_options': [u'--disable-e10s'], 10:18:07 INFO - u'tests': [u'a11yr', 10:18:07 INFO - u'ts_paint', 10:18:07 INFO - u'tpaint', 10:18:07 INFO - u'sessionrestore', 10:18:07 INFO - u'sessionrestore_no_auto_restore', 10:18:07 INFO - u'tabpaint']}, 10:18:07 INFO - u'other-e10s': {u'tests': [u'a11yr', 10:18:07 INFO - u'ts_paint', 10:18:07 INFO - u'tpaint', 10:18:07 INFO - u'sessionrestore', 10:18:07 INFO - u'sessionrestore_no_auto_restore', 10:18:07 INFO - u'tabpaint']}, 10:18:07 INFO - u'other-e10s_l64': {u'tests': [u'a11yr', 10:18:07 INFO - u'ts_paint', 10:18:07 INFO - u'tpaint', 10:18:07 INFO - u'sessionrestore', 10:18:07 INFO - u'sessionrestore_no_auto_restore', 10:18:07 INFO - u'tabpaint']}, 10:18:07 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr', 10:18:07 INFO - u'ts_paint', 10:18:07 INFO - u'tpaint', 10:18:07 INFO - u'sessionrestore', 10:18:07 INFO - u'sessionrestore_no_auto_restore', 10:18:07 INFO - u'tabpaint']}, 10:18:07 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'], 10:18:07 INFO - u'tests': [u'a11yr', 10:18:07 INFO - u'ts_paint', 10:18:07 INFO - u'tpaint', 10:18:07 INFO - u'sessionrestore', 10:18:07 INFO - u'sessionrestore_no_auto_restore', 10:18:07 INFO - u'tabpaint']}, 10:18:07 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'], 10:18:07 INFO - u'tests': [u'a11yr', 10:18:07 INFO - u'ts_paint', 10:18:07 INFO - u'tpaint', 10:18:07 INFO - u'sessionrestore', 10:18:07 INFO - u'sessionrestore_no_auto_restore', 10:18:07 INFO - u'tabpaint']}, 10:18:07 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'], 10:18:07 INFO - u'tests': [u'tsvgx', 10:18:07 INFO - u'tsvgr_opacity', 10:18:07 INFO - u'tart', 10:18:07 INFO - u'tscrollx', 10:18:07 INFO - u'cart']}, 10:18:07 INFO - u'svgr-e10s': {u'tests': [u'tsvgx', 10:18:07 INFO - u'tsvgr_opacity', 10:18:07 INFO - u'tart', 10:18:07 INFO - u'tscrollx', 10:18:07 INFO - u'cart']}, 10:18:07 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:18:07 INFO - u'talos_options': [u'--disable-e10s'], 10:18:07 INFO - u'tests': [u'tp5o']}, 10:18:07 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:18:07 INFO - u'tests': [u'tp5o']}, 10:18:07 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:18:07 INFO - u'talos_options': [u'--disable-e10s', 10:18:07 INFO - u'--xperf_path', 10:18:07 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:18:07 INFO - u'tests': [u'tp5n']}, 10:18:07 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:18:07 INFO - u'talos_options': [u'--xperf_path', 10:18:07 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:18:07 INFO - u'tests': [u'tp5n']}}, 10:18:07 INFO - u'talos.zip': {u'path': u'', 10:18:07 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:18:07 INFO - Running post-action listener: _resource_record_post_action 10:18:07 INFO - [mozharness: 2016-10-16 17:18:07.945000Z] Finished populate-webroot step (success) 10:18:07 INFO - [mozharness: 2016-10-16 17:18:07.945000Z] Running create-virtualenv step. 10:18:07 INFO - Running pre-action listener: _resource_record_pre_action 10:18:07 INFO - Running main action method: create_virtualenv 10:18:07 INFO - Creating virtualenv C:\slave\test\build/venv 10:18:07 INFO - mkdir: C:\slave\test\build\venv\Scripts 10:18:08 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll 10:18:08 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'! 10:18:08 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 10:18:08 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv 10:18:18 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe 10:18:24 INFO - Installing distribute......................................................................................................................................................................................done. 10:18:26 INFO - Return code: 0 10:18:26 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version'] 10:18:26 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version 10:18:26 INFO - Reading from file tmpfile_stdout 10:18:26 INFO - Output received: 10:18:26 INFO - pip 0.8.2 from c:\slave\test\build\venv\lib\site-packages\pip-0.8.2-py2.7.egg (python 2.7) 10:18:26 INFO - Using _rmtree_windows ... 10:18:26 INFO - Using _rmtree_windows ... 10:18:26 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv 10:18:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:18:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:18:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:18:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:18:26 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018E8640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01922E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01862B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0193CE90>, '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 0x0133A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019A8520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-034', 'USERDOMAIN': 'T-W732-IX-034', '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-034', '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 10:18:26 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in C:\slave\test\build 10:18:26 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:18:26 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 10:18:26 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 10:18:26 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 10:18:26 INFO - 'COMPUTERNAME': 'T-W732-IX-034', 10:18:26 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 10:18:26 INFO - 'CYGWINBASE': 'C:\\cygwin', 10:18:26 INFO - 'DCLOCATION': 'SCL3', 10:18:26 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 10:18:26 INFO - 'FP_NO_HOST_CHECK': 'NO', 10:18:26 INFO - 'HOMEDRIVE': 'C:', 10:18:26 INFO - 'HOMEPATH': '\\Users\\cltbld', 10:18:26 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 10:18:26 INFO - 'KTS_VERSION': '1.19c', 10:18:26 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 10:18:26 INFO - 'LOGONSERVER': '\\\\T-W732-IX-034', 10:18:26 INFO - 'MONDIR': 'C:\\Monitor_config\\', 10:18:26 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 10:18:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:18:26 INFO - 'MOZ_NO_REMOTE': '1', 10:18:26 INFO - 'NO_EM_RESTART': '1', 10:18:26 INFO - 'NUMBER_OF_PROCESSORS': '8', 10:18:26 INFO - 'OS': 'Windows_NT', 10:18:26 INFO - 'OURDRIVE': 'C:', 10:18:26 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 10:18:26 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 10:18:26 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 10:18:26 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 10:18:26 INFO - 'PROCESSOR_LEVEL': '6', 10:18:26 INFO - 'PROCESSOR_REVISION': '1e05', 10:18:26 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 10:18:26 INFO - 'PROGRAMFILES': 'C:\\Program Files', 10:18:26 INFO - 'PROMPT': '$P$G', 10:18:26 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 10:18:26 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 10:18:26 INFO - 'PUBLIC': 'C:\\Users\\Public', 10:18:26 INFO - 'PWD': 'C:\\slave\\test', 10:18:26 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 10:18:26 INFO - 'SLAVEDIR': 'C:\\slave\\', 10:18:26 INFO - 'SYSTEMDRIVE': 'C:', 10:18:26 INFO - 'SYSTEMROOT': 'C:\\windows', 10:18:26 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:18:26 INFO - 'TEST1': 'testie', 10:18:26 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:18:26 INFO - 'USERDOMAIN': 'T-W732-IX-034', 10:18:26 INFO - 'USERNAME': 'cltbld', 10:18:26 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 10:18:26 INFO - 'WINDIR': 'C:\\windows', 10:18:26 INFO - 'WINDOWS_TRACING_FLAGS': '3', 10:18:26 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 10:18:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 10:18:27 INFO - Ignoring indexes: http://pypi.python.org/simple/ 10:18:27 INFO - Downloading/unpacking pip>=1.5 10:18:31 INFO - Running setup.py egg_info for package pip 10:18:31 INFO - warning: no files found matching 'pip\cacert.pem' 10:18:31 INFO - warning: no files found matching '*.html' under directory 'docs' 10:18:31 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 10:18:31 INFO - no previously-included directories found matching 'docs\_build\_sources' 10:18:31 INFO - Installing collected packages: pip 10:18:31 INFO - Found existing installation: pip 0.8.2 10:18:31 INFO - Uninstalling pip: 10:18:31 INFO - Successfully uninstalled pip 10:18:31 INFO - Running setup.py install for pip 10:18:31 INFO - warning: no files found matching 'pip\cacert.pem' 10:18:31 INFO - warning: no files found matching '*.html' under directory 'docs' 10:18:31 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 10:18:31 INFO - no previously-included directories found matching 'docs\_build\_sources' 10:18:31 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts 10:18:31 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts 10:18:31 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts 10:18:31 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts 10:18:31 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts 10:18:31 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts 10:18:31 INFO - Successfully installed pip 10:18:31 INFO - Cleaning up... 10:18:31 INFO - Return code: 0 10:18:31 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv 10:18:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:18:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:18:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:18:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:18:31 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x018E8640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01922E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01862B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0193CE90>, '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 0x0133A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019A8520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-034', 'USERDOMAIN': 'T-W732-IX-034', '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-034', '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 10:18:31 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in C:\slave\test\build 10:18:31 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 10:18:31 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 10:18:31 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 10:18:31 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 10:18:31 INFO - 'COMPUTERNAME': 'T-W732-IX-034', 10:18:31 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 10:18:31 INFO - 'CYGWINBASE': 'C:\\cygwin', 10:18:31 INFO - 'DCLOCATION': 'SCL3', 10:18:31 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 10:18:31 INFO - 'FP_NO_HOST_CHECK': 'NO', 10:18:31 INFO - 'HOMEDRIVE': 'C:', 10:18:31 INFO - 'HOMEPATH': '\\Users\\cltbld', 10:18:31 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 10:18:31 INFO - 'KTS_VERSION': '1.19c', 10:18:31 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 10:18:31 INFO - 'LOGONSERVER': '\\\\T-W732-IX-034', 10:18:31 INFO - 'MONDIR': 'C:\\Monitor_config\\', 10:18:31 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 10:18:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:18:31 INFO - 'MOZ_NO_REMOTE': '1', 10:18:31 INFO - 'NO_EM_RESTART': '1', 10:18:31 INFO - 'NUMBER_OF_PROCESSORS': '8', 10:18:31 INFO - 'OS': 'Windows_NT', 10:18:31 INFO - 'OURDRIVE': 'C:', 10:18:31 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 10:18:31 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 10:18:31 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 10:18:31 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 10:18:31 INFO - 'PROCESSOR_LEVEL': '6', 10:18:31 INFO - 'PROCESSOR_REVISION': '1e05', 10:18:31 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 10:18:31 INFO - 'PROGRAMFILES': 'C:\\Program Files', 10:18:31 INFO - 'PROMPT': '$P$G', 10:18:31 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 10:18:31 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 10:18:31 INFO - 'PUBLIC': 'C:\\Users\\Public', 10:18:31 INFO - 'PWD': 'C:\\slave\\test', 10:18:31 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 10:18:31 INFO - 'SLAVEDIR': 'C:\\slave\\', 10:18:31 INFO - 'SYSTEMDRIVE': 'C:', 10:18:31 INFO - 'SYSTEMROOT': 'C:\\windows', 10:18:31 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:18:31 INFO - 'TEST1': 'testie', 10:18:31 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:18:31 INFO - 'USERDOMAIN': 'T-W732-IX-034', 10:18:31 INFO - 'USERNAME': 'cltbld', 10:18:31 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 10:18:31 INFO - 'WINDIR': 'C:\\windows', 10:18:31 INFO - 'WINDOWS_TRACING_FLAGS': '3', 10:18:31 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 10:18:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 10:18:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:18:32 INFO - Downloading/unpacking psutil>=3.1.1 10:18:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:18:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:18:34 INFO - Installing collected packages: psutil 10:18:34 INFO - Successfully installed psutil 10:18:34 INFO - Cleaning up... 10:18:34 INFO - Return code: 0 10:18:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv 10:18:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:18:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:18:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:18:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:18:34 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018E8640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01922E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01862B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0193CE90>, '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 0x0133A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019A8520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-034', 'USERDOMAIN': 'T-W732-IX-034', '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-034', '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 10:18:34 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in C:\slave\test\build 10:18:34 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:18:34 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 10:18:34 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 10:18:34 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 10:18:34 INFO - 'COMPUTERNAME': 'T-W732-IX-034', 10:18:34 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 10:18:34 INFO - 'CYGWINBASE': 'C:\\cygwin', 10:18:34 INFO - 'DCLOCATION': 'SCL3', 10:18:34 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 10:18:34 INFO - 'FP_NO_HOST_CHECK': 'NO', 10:18:34 INFO - 'HOMEDRIVE': 'C:', 10:18:34 INFO - 'HOMEPATH': '\\Users\\cltbld', 10:18:34 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 10:18:34 INFO - 'KTS_VERSION': '1.19c', 10:18:34 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 10:18:34 INFO - 'LOGONSERVER': '\\\\T-W732-IX-034', 10:18:34 INFO - 'MONDIR': 'C:\\Monitor_config\\', 10:18:34 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 10:18:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:18:34 INFO - 'MOZ_NO_REMOTE': '1', 10:18:34 INFO - 'NO_EM_RESTART': '1', 10:18:34 INFO - 'NUMBER_OF_PROCESSORS': '8', 10:18:34 INFO - 'OS': 'Windows_NT', 10:18:34 INFO - 'OURDRIVE': 'C:', 10:18:34 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 10:18:34 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 10:18:34 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 10:18:34 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 10:18:34 INFO - 'PROCESSOR_LEVEL': '6', 10:18:34 INFO - 'PROCESSOR_REVISION': '1e05', 10:18:34 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 10:18:34 INFO - 'PROGRAMFILES': 'C:\\Program Files', 10:18:34 INFO - 'PROMPT': '$P$G', 10:18:34 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 10:18:34 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 10:18:34 INFO - 'PUBLIC': 'C:\\Users\\Public', 10:18:34 INFO - 'PWD': 'C:\\slave\\test', 10:18:34 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 10:18:34 INFO - 'SLAVEDIR': 'C:\\slave\\', 10:18:34 INFO - 'SYSTEMDRIVE': 'C:', 10:18:34 INFO - 'SYSTEMROOT': 'C:\\windows', 10:18:34 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:18:34 INFO - 'TEST1': 'testie', 10:18:34 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:18:34 INFO - 'USERDOMAIN': 'T-W732-IX-034', 10:18:34 INFO - 'USERNAME': 'cltbld', 10:18:34 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 10:18:34 INFO - 'WINDIR': 'C:\\windows', 10:18:34 INFO - 'WINDOWS_TRACING_FLAGS': '3', 10:18:34 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 10:18:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 10:18:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:18:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:18:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:18:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:18:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:18:35 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 10:18:35 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) 10:18:35 INFO - Installing collected packages: mozsystemmonitor 10:18:35 INFO - Running setup.py install for mozsystemmonitor 10:18:35 INFO - Successfully installed mozsystemmonitor 10:18:35 INFO - Cleaning up... 10:18:36 INFO - Return code: 0 10:18:36 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv 10:18:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:18:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:18:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:18:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:18:36 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x018E8640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01922E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01862B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0193CE90>, '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 0x0133A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019A8520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-034', 'USERDOMAIN': 'T-W732-IX-034', '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-034', '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 10:18:36 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in C:\slave\test\build 10:18:36 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 10:18:36 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 10:18:36 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 10:18:36 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 10:18:36 INFO - 'COMPUTERNAME': 'T-W732-IX-034', 10:18:36 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 10:18:36 INFO - 'CYGWINBASE': 'C:\\cygwin', 10:18:36 INFO - 'DCLOCATION': 'SCL3', 10:18:36 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 10:18:36 INFO - 'FP_NO_HOST_CHECK': 'NO', 10:18:36 INFO - 'HOMEDRIVE': 'C:', 10:18:36 INFO - 'HOMEPATH': '\\Users\\cltbld', 10:18:36 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 10:18:36 INFO - 'KTS_VERSION': '1.19c', 10:18:36 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 10:18:36 INFO - 'LOGONSERVER': '\\\\T-W732-IX-034', 10:18:36 INFO - 'MONDIR': 'C:\\Monitor_config\\', 10:18:36 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 10:18:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:18:36 INFO - 'MOZ_NO_REMOTE': '1', 10:18:36 INFO - 'NO_EM_RESTART': '1', 10:18:36 INFO - 'NUMBER_OF_PROCESSORS': '8', 10:18:36 INFO - 'OS': 'Windows_NT', 10:18:36 INFO - 'OURDRIVE': 'C:', 10:18:36 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 10:18:36 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 10:18:36 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 10:18:36 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 10:18:36 INFO - 'PROCESSOR_LEVEL': '6', 10:18:36 INFO - 'PROCESSOR_REVISION': '1e05', 10:18:36 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 10:18:36 INFO - 'PROGRAMFILES': 'C:\\Program Files', 10:18:36 INFO - 'PROMPT': '$P$G', 10:18:36 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 10:18:36 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 10:18:36 INFO - 'PUBLIC': 'C:\\Users\\Public', 10:18:36 INFO - 'PWD': 'C:\\slave\\test', 10:18:36 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 10:18:36 INFO - 'SLAVEDIR': 'C:\\slave\\', 10:18:36 INFO - 'SYSTEMDRIVE': 'C:', 10:18:36 INFO - 'SYSTEMROOT': 'C:\\windows', 10:18:36 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:18:36 INFO - 'TEST1': 'testie', 10:18:36 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:18:36 INFO - 'USERDOMAIN': 'T-W732-IX-034', 10:18:36 INFO - 'USERNAME': 'cltbld', 10:18:36 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 10:18:36 INFO - 'WINDIR': 'C:\\windows', 10:18:36 INFO - 'WINDOWS_TRACING_FLAGS': '3', 10:18:36 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 10:18:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 10:18:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:18:36 INFO - Downloading/unpacking jsonschema==2.5.1 10:18:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:18:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:18:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:18:37 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:18:37 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 10:18:37 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 10:18:37 INFO - Downloading functools32-3.2.3-2.tar.gz 10:18:37 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32 10:18:37 INFO - warning: no files found matching '*.txt' 10:18:37 INFO - no previously-included directories found matching 'build' 10:18:37 INFO - no previously-included directories found matching 'dist' 10:18:37 INFO - no previously-included directories found matching '.git*' 10:18:37 INFO - Installing collected packages: jsonschema, functools32 10:18:37 INFO - Running setup.py install for functools32 10:18:38 INFO - warning: no files found matching '*.txt' 10:18:38 INFO - no previously-included directories found matching 'build' 10:18:38 INFO - no previously-included directories found matching 'dist' 10:18:38 INFO - no previously-included directories found matching '.git*' 10:18:38 INFO - Successfully installed jsonschema functools32 10:18:38 INFO - Cleaning up... 10:18:38 INFO - Return code: 0 10:18:38 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv 10:18:38 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')]} 10:18:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:18:38 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')]} 10:18:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:18:38 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 0x018E8640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01922E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01862B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0193CE90>, '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 0x0133A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019A8520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-034', 'USERDOMAIN': 'T-W732-IX-034', '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-034', '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 10:18:38 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 10:18:38 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 10:18:38 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 10:18:38 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 10:18:38 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 10:18:38 INFO - 'COMPUTERNAME': 'T-W732-IX-034', 10:18:38 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 10:18:38 INFO - 'CYGWINBASE': 'C:\\cygwin', 10:18:38 INFO - 'DCLOCATION': 'SCL3', 10:18:38 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 10:18:38 INFO - 'FP_NO_HOST_CHECK': 'NO', 10:18:38 INFO - 'HOMEDRIVE': 'C:', 10:18:38 INFO - 'HOMEPATH': '\\Users\\cltbld', 10:18:38 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 10:18:38 INFO - 'KTS_VERSION': '1.19c', 10:18:38 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 10:18:38 INFO - 'LOGONSERVER': '\\\\T-W732-IX-034', 10:18:38 INFO - 'MONDIR': 'C:\\Monitor_config\\', 10:18:38 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 10:18:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:18:38 INFO - 'MOZ_NO_REMOTE': '1', 10:18:38 INFO - 'NO_EM_RESTART': '1', 10:18:38 INFO - 'NUMBER_OF_PROCESSORS': '8', 10:18:38 INFO - 'OS': 'Windows_NT', 10:18:38 INFO - 'OURDRIVE': 'C:', 10:18:38 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;', 10:18:38 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 10:18:38 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 10:18:38 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 10:18:38 INFO - 'PROCESSOR_LEVEL': '6', 10:18:38 INFO - 'PROCESSOR_REVISION': '1e05', 10:18:38 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 10:18:38 INFO - 'PROGRAMFILES': 'C:\\Program Files', 10:18:38 INFO - 'PROMPT': '$P$G', 10:18:38 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 10:18:38 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 10:18:38 INFO - 'PUBLIC': 'C:\\Users\\Public', 10:18:38 INFO - 'PWD': 'C:\\slave\\test', 10:18:38 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 10:18:38 INFO - 'SLAVEDIR': 'C:\\slave\\', 10:18:38 INFO - 'SYSTEMDRIVE': 'C:', 10:18:38 INFO - 'SYSTEMROOT': 'C:\\windows', 10:18:38 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:18:38 INFO - 'TEST1': 'testie', 10:18:38 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:18:38 INFO - 'USERDOMAIN': 'T-W732-IX-034', 10:18:38 INFO - 'USERNAME': 'cltbld', 10:18:38 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 10:18:38 INFO - 'WINDIR': 'C:\\windows', 10:18:38 INFO - 'WINDOWS_TRACING_FLAGS': '3', 10:18:38 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 10:18:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 10:18:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:18:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages 10:18:39 INFO - Cleaning up... 10:18:39 INFO - Return code: 0 10:18:39 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv 10:18:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:18:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:18:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:18:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:18:39 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x018E8640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01922E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01862B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0193CE90>, '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 0x0133A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019A8520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-034', 'USERDOMAIN': 'T-W732-IX-034', '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-034', '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 10:18:39 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in C:\slave\test\build 10:18:39 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:18:39 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 10:18:39 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 10:18:39 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 10:18:39 INFO - 'COMPUTERNAME': 'T-W732-IX-034', 10:18:39 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 10:18:39 INFO - 'CYGWINBASE': 'C:\\cygwin', 10:18:39 INFO - 'DCLOCATION': 'SCL3', 10:18:39 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 10:18:39 INFO - 'FP_NO_HOST_CHECK': 'NO', 10:18:39 INFO - 'HOMEDRIVE': 'C:', 10:18:39 INFO - 'HOMEPATH': '\\Users\\cltbld', 10:18:39 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 10:18:39 INFO - 'KTS_VERSION': '1.19c', 10:18:39 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 10:18:39 INFO - 'LOGONSERVER': '\\\\T-W732-IX-034', 10:18:39 INFO - 'MONDIR': 'C:\\Monitor_config\\', 10:18:39 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 10:18:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:18:39 INFO - 'MOZ_NO_REMOTE': '1', 10:18:39 INFO - 'NO_EM_RESTART': '1', 10:18:39 INFO - 'NUMBER_OF_PROCESSORS': '8', 10:18:39 INFO - 'OS': 'Windows_NT', 10:18:39 INFO - 'OURDRIVE': 'C:', 10:18:39 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 10:18:39 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 10:18:39 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 10:18:39 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 10:18:39 INFO - 'PROCESSOR_LEVEL': '6', 10:18:39 INFO - 'PROCESSOR_REVISION': '1e05', 10:18:39 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 10:18:39 INFO - 'PROGRAMFILES': 'C:\\Program Files', 10:18:39 INFO - 'PROMPT': '$P$G', 10:18:39 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 10:18:39 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 10:18:39 INFO - 'PUBLIC': 'C:\\Users\\Public', 10:18:39 INFO - 'PWD': 'C:\\slave\\test', 10:18:39 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 10:18:39 INFO - 'SLAVEDIR': 'C:\\slave\\', 10:18:39 INFO - 'SYSTEMDRIVE': 'C:', 10:18:39 INFO - 'SYSTEMROOT': 'C:\\windows', 10:18:39 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:18:39 INFO - 'TEST1': 'testie', 10:18:39 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:18:39 INFO - 'USERDOMAIN': 'T-W732-IX-034', 10:18:39 INFO - 'USERNAME': 'cltbld', 10:18:39 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 10:18:39 INFO - 'WINDIR': 'C:\\windows', 10:18:39 INFO - 'WINDOWS_TRACING_FLAGS': '3', 10:18:39 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 10:18:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 10:18:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:18:39 INFO - Downloading/unpacking blobuploader==1.2.4 10:18:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:18:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:18:40 INFO - Downloading blobuploader-1.2.4.tar.gz 10:18:40 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 10:18:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:18:40 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 10:18:40 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 10:18:40 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests 10:18:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:18:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:18:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:18:41 INFO - Downloading docopt-0.6.1.tar.gz 10:18:41 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt 10:18:41 INFO - Installing collected packages: blobuploader, requests, docopt 10:18:41 INFO - Running setup.py install for blobuploader 10:18:41 INFO - Running setup.py install for requests 10:18:42 INFO - Running setup.py install for docopt 10:18:42 INFO - Successfully installed blobuploader requests docopt 10:18:42 INFO - Cleaning up... 10:18:45 INFO - Return code: 0 10:18:45 INFO - Installing None into virtualenv C:\slave\test\build/venv 10:18:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:18:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:18:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:18:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:18:45 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 0x018E8640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01922E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01862B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0193CE90>, '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 0x0133A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019A8520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-034', 'USERDOMAIN': 'T-W732-IX-034', '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-034', '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 10:18:45 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 10:18:45 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 10:18:45 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 10:18:45 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 10:18:45 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 10:18:45 INFO - 'COMPUTERNAME': 'T-W732-IX-034', 10:18:45 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 10:18:45 INFO - 'CYGWINBASE': 'C:\\cygwin', 10:18:45 INFO - 'DCLOCATION': 'SCL3', 10:18:45 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 10:18:45 INFO - 'FP_NO_HOST_CHECK': 'NO', 10:18:45 INFO - 'HOMEDRIVE': 'C:', 10:18:45 INFO - 'HOMEPATH': '\\Users\\cltbld', 10:18:45 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 10:18:45 INFO - 'KTS_VERSION': '1.19c', 10:18:45 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 10:18:45 INFO - 'LOGONSERVER': '\\\\T-W732-IX-034', 10:18:45 INFO - 'MONDIR': 'C:\\Monitor_config\\', 10:18:45 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 10:18:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:18:45 INFO - 'MOZ_NO_REMOTE': '1', 10:18:45 INFO - 'NO_EM_RESTART': '1', 10:18:45 INFO - 'NUMBER_OF_PROCESSORS': '8', 10:18:45 INFO - 'OS': 'Windows_NT', 10:18:45 INFO - 'OURDRIVE': 'C:', 10:18:45 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;', 10:18:45 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 10:18:45 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 10:18:45 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 10:18:45 INFO - 'PROCESSOR_LEVEL': '6', 10:18:45 INFO - 'PROCESSOR_REVISION': '1e05', 10:18:45 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 10:18:45 INFO - 'PROGRAMFILES': 'C:\\Program Files', 10:18:45 INFO - 'PROMPT': '$P$G', 10:18:45 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 10:18:45 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 10:18:45 INFO - 'PUBLIC': 'C:\\Users\\Public', 10:18:45 INFO - 'PWD': 'C:\\slave\\test', 10:18:45 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 10:18:45 INFO - 'SLAVEDIR': 'C:\\slave\\', 10:18:45 INFO - 'SYSTEMDRIVE': 'C:', 10:18:45 INFO - 'SYSTEMROOT': 'C:\\windows', 10:18:45 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:18:45 INFO - 'TEST1': 'testie', 10:18:45 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:18:45 INFO - 'USERDOMAIN': 'T-W732-IX-034', 10:18:45 INFO - 'USERNAME': 'cltbld', 10:18:45 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 10:18:45 INFO - 'WINDIR': 'C:\\windows', 10:18:45 INFO - 'WINDOWS_TRACING_FLAGS': '3', 10:18:45 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 10:18:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 10:18:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:18:45 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 10:18:45 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-47vv9c-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 10:18:45 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 10:18:45 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-q_b4i6-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 10:18:45 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 10:18:45 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ussnwy-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 10:18:45 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 10:18:45 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-k34rpx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 10:18:46 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 10:18:46 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bar9sg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 10:18:46 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 10:18:46 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qo3nuo-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 10:18:46 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 10:18:46 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-i8xtbq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 10:18:46 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 10:18:46 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-yagkhh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 10:18:46 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 10:18:46 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-sl8cbk-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 10:18:46 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 10:18:46 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-e5o7kg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 10:18:46 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 10:18:46 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-1o9f7v-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 10:18:46 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 10:18:46 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-sa_en0-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 10:18:47 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 10:18:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-2gby7q-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 10:18:47 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 10:18:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hbbcga-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 10:18:47 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 10:18:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-derl6t-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 10:18:47 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 10:18:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-uzkchk-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 10:18:47 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 10:18:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-1lg4ke-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 10:18:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:18:47 INFO - Running setup.py install for manifestparser 10:18:48 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts 10:18:48 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts 10:18:48 INFO - Running setup.py install for mozcrash 10:18:49 INFO - Running setup.py install for mozdebug 10:18:49 INFO - Running setup.py install for mozdevice 10:18:49 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts 10:18:49 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts 10:18:49 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts 10:18:50 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts 10:18:50 INFO - Running setup.py install for mozfile 10:18:50 INFO - Running setup.py install for mozhttpd 10:18:50 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts 10:18:50 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts 10:18:51 INFO - Running setup.py install for mozinfo 10:18:51 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts 10:18:51 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts 10:18:51 INFO - Running setup.py install for mozInstall 10:18:51 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts 10:18:51 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts 10:18:51 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts 10:18:51 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts 10:18:51 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts 10:18:51 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts 10:18:51 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts 10:18:51 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts 10:18:51 INFO - Running setup.py install for mozleak 10:18:52 INFO - Running setup.py install for mozlog 10:18:52 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts 10:18:52 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts 10:18:53 INFO - Running setup.py install for moznetwork 10:18:53 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts 10:18:53 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts 10:18:53 INFO - Running setup.py install for mozprocess 10:18:53 INFO - Running setup.py install for mozprofile 10:18:54 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts 10:18:54 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts 10:18:54 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts 10:18:54 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts 10:18:54 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts 10:18:54 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts 10:18:54 INFO - Running setup.py install for mozrunner 10:18:54 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts 10:18:54 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts 10:18:55 INFO - Running setup.py install for mozscreenshot 10:18:55 INFO - Running setup.py install for moztest 10:18:55 INFO - Running setup.py install for mozversion 10:18:56 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts 10:18:56 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts 10:18:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:18:56 INFO - Cleaning up... 10:18:56 INFO - Return code: 0 10:18:56 INFO - Installing None into virtualenv C:\slave\test\build/venv 10:18:56 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')]} 10:18:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:18:56 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')]} 10:18:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:18:56 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 0x018E8640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01922E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01862B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0193CE90>, '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 0x0133A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019A8520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-034', 'USERDOMAIN': 'T-W732-IX-034', '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-034', '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 10:18:56 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 10:18:56 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 10:18:56 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 10:18:56 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 10:18:56 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 10:18:56 INFO - 'COMPUTERNAME': 'T-W732-IX-034', 10:18:56 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 10:18:56 INFO - 'CYGWINBASE': 'C:\\cygwin', 10:18:56 INFO - 'DCLOCATION': 'SCL3', 10:18:56 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 10:18:56 INFO - 'FP_NO_HOST_CHECK': 'NO', 10:18:56 INFO - 'HOMEDRIVE': 'C:', 10:18:56 INFO - 'HOMEPATH': '\\Users\\cltbld', 10:18:56 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 10:18:56 INFO - 'KTS_VERSION': '1.19c', 10:18:56 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 10:18:56 INFO - 'LOGONSERVER': '\\\\T-W732-IX-034', 10:18:56 INFO - 'MONDIR': 'C:\\Monitor_config\\', 10:18:56 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 10:18:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:18:56 INFO - 'MOZ_NO_REMOTE': '1', 10:18:56 INFO - 'NO_EM_RESTART': '1', 10:18:56 INFO - 'NUMBER_OF_PROCESSORS': '8', 10:18:56 INFO - 'OS': 'Windows_NT', 10:18:56 INFO - 'OURDRIVE': 'C:', 10:18:56 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;', 10:18:56 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 10:18:56 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 10:18:56 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 10:18:56 INFO - 'PROCESSOR_LEVEL': '6', 10:18:56 INFO - 'PROCESSOR_REVISION': '1e05', 10:18:56 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 10:18:56 INFO - 'PROGRAMFILES': 'C:\\Program Files', 10:18:56 INFO - 'PROMPT': '$P$G', 10:18:56 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 10:18:56 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 10:18:56 INFO - 'PUBLIC': 'C:\\Users\\Public', 10:18:56 INFO - 'PWD': 'C:\\slave\\test', 10:18:56 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 10:18:56 INFO - 'SLAVEDIR': 'C:\\slave\\', 10:18:56 INFO - 'SYSTEMDRIVE': 'C:', 10:18:56 INFO - 'SYSTEMROOT': 'C:\\windows', 10:18:56 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:18:56 INFO - 'TEST1': 'testie', 10:18:56 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:18:56 INFO - 'USERDOMAIN': 'T-W732-IX-034', 10:18:56 INFO - 'USERNAME': 'cltbld', 10:18:56 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 10:18:56 INFO - 'WINDIR': 'C:\\windows', 10:18:56 INFO - 'WINDOWS_TRACING_FLAGS': '3', 10:18:56 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 10:18:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 10:18:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:18:56 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 10:18:56 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rsna_j-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 10:18:56 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)) 10:18:56 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 10:18:56 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xq4ud4-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 10:18:57 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)) 10:18:57 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 10:18:57 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-3plite-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 10:18:57 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)) 10:18:57 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 10:18:57 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wztgxr-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 10:18:57 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)) 10:18:57 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 10:18:57 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kavsgz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 10:18:57 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)) 10:18:57 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 10:18:57 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-sknlqa-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 10:18:57 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)) 10:18:57 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 10:18:57 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hkxixn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 10:18:57 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)) 10:18:57 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 10:18:57 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-tqvbl0-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 10:18:57 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)) 10:18:57 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 10:18:57 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-drzvdh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 10:18:58 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)) 10:18:58 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 10:18:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-s5zx2y-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 10:18:58 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)) 10:18:58 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 10:18:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xf6_jv-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 10:18:58 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)) 10:18:58 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 10:18:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-tzk6eq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 10:18:58 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)) 10:18:58 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 10:18:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ejhcpw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 10:18:58 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)) 10:18:58 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 10:18:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-nsi_m1-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 10:18:58 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)) 10:18:58 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 10:18:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-4c6weq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 10:18:58 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)) 10:18:58 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 10:18:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rhbgud-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 10:18:59 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)) 10:18:59 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 10:18:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vevcyu-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 10:18:59 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)) 10:18:59 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)) 10:18:59 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)) 10:18:59 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)) 10:18:59 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)) 10:18:59 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)) 10:18:59 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10)) 10:18:59 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 10:18:59 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 10:19:00 INFO - Downloading blessings-1.6.tar.gz 10:19:00 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings 10:19:00 INFO - Installing collected packages: blessings 10:19:00 INFO - Running setup.py install for blessings 10:19:00 INFO - Successfully installed blessings 10:19:00 INFO - Cleaning up... 10:19:03 INFO - Return code: 0 10:19:03 INFO - Done creating virtualenv C:\slave\test\build/venv. 10:19:03 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 10:19:03 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 10:19:03 INFO - Reading from file tmpfile_stdout 10:19:03 INFO - Using _rmtree_windows ... 10:19:03 INFO - Using _rmtree_windows ... 10:19:03 INFO - Current package versions: 10:19:03 INFO - blessings == 1.6 10:19:03 INFO - blobuploader == 1.2.4 10:19:03 INFO - docopt == 0.6.1 10:19:03 INFO - functools32 == 3.2.3-2 10:19:03 INFO - jsonschema == 2.5.1 10:19:03 INFO - manifestparser == 1.1 10:19:03 INFO - mozInstall == 1.12 10:19:03 INFO - mozcrash == 0.17 10:19:03 INFO - mozdebug == 0.1 10:19:03 INFO - mozdevice == 0.48 10:19:03 INFO - mozfile == 1.2 10:19:03 INFO - mozhttpd == 0.7 10:19:03 INFO - mozinfo == 0.9 10:19:03 INFO - mozleak == 0.1 10:19:03 INFO - mozlog == 3.4 10:19:03 INFO - moznetwork == 0.27 10:19:03 INFO - mozprocess == 0.23 10:19:03 INFO - mozprofile == 0.28 10:19:03 INFO - mozrunner == 6.12 10:19:03 INFO - mozscreenshot == 0.1 10:19:03 INFO - mozsystemmonitor == 0.3 10:19:03 INFO - moztest == 0.7 10:19:03 INFO - mozversion == 1.4 10:19:03 INFO - psutil == 3.1.1 10:19:03 INFO - requests == 1.2.3 10:19:03 INFO - Installing None into virtualenv C:\slave\test\build/venv 10:19:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:19:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:19:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:19:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:19:03 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 0x018E8640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01922E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01862B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0193CE90>, '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 0x0133A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019A8520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-034', 'USERDOMAIN': 'T-W732-IX-034', '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-034', '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 10:19:04 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 10:19:04 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 10:19:04 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 10:19:04 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 10:19:04 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 10:19:04 INFO - 'COMPUTERNAME': 'T-W732-IX-034', 10:19:04 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 10:19:04 INFO - 'CYGWINBASE': 'C:\\cygwin', 10:19:04 INFO - 'DCLOCATION': 'SCL3', 10:19:04 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 10:19:04 INFO - 'FP_NO_HOST_CHECK': 'NO', 10:19:04 INFO - 'HOMEDRIVE': 'C:', 10:19:04 INFO - 'HOMEPATH': '\\Users\\cltbld', 10:19:04 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 10:19:04 INFO - 'KTS_VERSION': '1.19c', 10:19:04 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 10:19:04 INFO - 'LOGONSERVER': '\\\\T-W732-IX-034', 10:19:04 INFO - 'MONDIR': 'C:\\Monitor_config\\', 10:19:04 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 10:19:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:19:04 INFO - 'MOZ_NO_REMOTE': '1', 10:19:04 INFO - 'NO_EM_RESTART': '1', 10:19:04 INFO - 'NUMBER_OF_PROCESSORS': '8', 10:19:04 INFO - 'OS': 'Windows_NT', 10:19:04 INFO - 'OURDRIVE': 'C:', 10:19:04 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;', 10:19:04 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 10:19:04 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 10:19:04 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 10:19:04 INFO - 'PROCESSOR_LEVEL': '6', 10:19:04 INFO - 'PROCESSOR_REVISION': '1e05', 10:19:04 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 10:19:04 INFO - 'PROGRAMFILES': 'C:\\Program Files', 10:19:04 INFO - 'PROMPT': '$P$G', 10:19:04 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 10:19:04 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 10:19:04 INFO - 'PUBLIC': 'C:\\Users\\Public', 10:19:04 INFO - 'PWD': 'C:\\slave\\test', 10:19:04 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 10:19:04 INFO - 'SLAVEDIR': 'C:\\slave\\', 10:19:04 INFO - 'SYSTEMDRIVE': 'C:', 10:19:04 INFO - 'SYSTEMROOT': 'C:\\windows', 10:19:04 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:19:04 INFO - 'TEST1': 'testie', 10:19:04 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:19:04 INFO - 'USERDOMAIN': 'T-W732-IX-034', 10:19:04 INFO - 'USERNAME': 'cltbld', 10:19:04 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 10:19:04 INFO - 'WINDIR': 'C:\\windows', 10:19:04 INFO - 'WINDOWS_TRACING_FLAGS': '3', 10:19:04 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 10:19:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 10:19:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:19:04 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)) 10:19:04 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)) 10:19:04 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)) 10:19:04 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)) 10:19:04 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)) 10:19:04 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)) 10:19:04 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)) 10:19:04 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)) 10:19:04 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)) 10:19:04 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)) 10:19:04 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)) 10:19:04 INFO - Cleaning up... 10:19:04 INFO - Return code: 0 10:19:04 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv 10:19:04 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')]} 10:19:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:19:04 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')]} 10:19:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:19:04 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 0x018E8640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01922E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01862B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0193CE90>, '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 0x0133A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019A8520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-034', 'USERDOMAIN': 'T-W732-IX-034', '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-034', '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 10:19:04 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 10:19:04 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 10:19:04 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 10:19:04 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 10:19:04 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 10:19:04 INFO - 'COMPUTERNAME': 'T-W732-IX-034', 10:19:04 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 10:19:04 INFO - 'CYGWINBASE': 'C:\\cygwin', 10:19:04 INFO - 'DCLOCATION': 'SCL3', 10:19:04 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 10:19:04 INFO - 'FP_NO_HOST_CHECK': 'NO', 10:19:04 INFO - 'HOMEDRIVE': 'C:', 10:19:04 INFO - 'HOMEPATH': '\\Users\\cltbld', 10:19:04 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 10:19:04 INFO - 'KTS_VERSION': '1.19c', 10:19:04 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 10:19:04 INFO - 'LOGONSERVER': '\\\\T-W732-IX-034', 10:19:04 INFO - 'MONDIR': 'C:\\Monitor_config\\', 10:19:04 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 10:19:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:19:04 INFO - 'MOZ_NO_REMOTE': '1', 10:19:04 INFO - 'NO_EM_RESTART': '1', 10:19:04 INFO - 'NUMBER_OF_PROCESSORS': '8', 10:19:04 INFO - 'OS': 'Windows_NT', 10:19:04 INFO - 'OURDRIVE': 'C:', 10:19:04 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;', 10:19:04 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 10:19:04 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 10:19:04 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 10:19:04 INFO - 'PROCESSOR_LEVEL': '6', 10:19:04 INFO - 'PROCESSOR_REVISION': '1e05', 10:19:04 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 10:19:04 INFO - 'PROGRAMFILES': 'C:\\Program Files', 10:19:04 INFO - 'PROMPT': '$P$G', 10:19:04 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 10:19:04 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 10:19:04 INFO - 'PUBLIC': 'C:\\Users\\Public', 10:19:04 INFO - 'PWD': 'C:\\slave\\test', 10:19:04 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 10:19:04 INFO - 'SLAVEDIR': 'C:\\slave\\', 10:19:04 INFO - 'SYSTEMDRIVE': 'C:', 10:19:04 INFO - 'SYSTEMROOT': 'C:\\windows', 10:19:04 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:19:04 INFO - 'TEST1': 'testie', 10:19:04 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:19:04 INFO - 'USERDOMAIN': 'T-W732-IX-034', 10:19:04 INFO - 'USERNAME': 'cltbld', 10:19:04 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 10:19:04 INFO - 'WINDIR': 'C:\\windows', 10:19:04 INFO - 'WINDOWS_TRACING_FLAGS': '3', 10:19:04 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 10:19:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 10:19:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:19:04 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages 10:19:04 INFO - Cleaning up... 10:19:04 INFO - Return code: 0 10:19:04 INFO - Running post-action listener: _resource_record_post_action 10:19:04 INFO - Running post-action listener: _start_resource_monitoring 10:19:04 INFO - Starting resource monitoring. 10:19:04 INFO - [mozharness: 2016-10-16 17:19:04.848000Z] Finished create-virtualenv step (success) 10:19:04 INFO - [mozharness: 2016-10-16 17:19:04.848000Z] Running install step. 10:19:04 INFO - Running pre-action listener: _resource_record_pre_action 10:19:04 INFO - Running main action method: install 10:19:04 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 10:19:04 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 10:19:05 INFO - Reading from file tmpfile_stdout 10:19:05 INFO - Using _rmtree_windows ... 10:19:05 INFO - Using _rmtree_windows ... 10:19:05 INFO - Detecting whether we're running mozinstall >=1.0... 10:19:05 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h'] 10:19:05 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h 10:19:05 INFO - Reading from file tmpfile_stdout 10:19:05 INFO - Output received: 10:19:05 INFO - Usage: mozinstall-script.py [options] installer 10:19:05 INFO - Options: 10:19:05 INFO - -h, --help show this help message and exit 10:19:05 INFO - -d DEST, --destination=DEST 10:19:05 INFO - Directory to install application into. [default: 10:19:05 INFO - "C:\slave\test"] 10:19:05 INFO - --app=APP Application being installed. [default: firefox] 10:19:05 INFO - Using _rmtree_windows ... 10:19:05 INFO - Using _rmtree_windows ... 10:19:05 INFO - mkdir: C:\slave\test\build\application 10:19:05 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'] 10:19:05 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 10:19:08 INFO - Reading from file tmpfile_stdout 10:19:08 INFO - Output received: 10:19:08 INFO - C:\slave\test\build\application\firefox\firefox.exe 10:19:08 INFO - Using _rmtree_windows ... 10:19:08 INFO - Using _rmtree_windows ... 10:19:08 INFO - Running post-action listener: _resource_record_post_action 10:19:08 INFO - [mozharness: 2016-10-16 17:19:08.873000Z] Finished install step (success) 10:19:08 INFO - [mozharness: 2016-10-16 17:19:08.873000Z] Running run-tests step. 10:19:08 INFO - Running pre-action listener: _resource_record_pre_action 10:19:08 INFO - Running main action method: run_tests 10:19:08 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version'] 10:19:08 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version 10:19:08 INFO - Python 2.7.3 10:19:08 INFO - Return code: 0 10:19:08 INFO - grabbing minidump binary from tooltool 10:19: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')]} 10:19:08 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 0x0193CE90>, '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 0x0133A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019A8520>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1 10:19:08 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 10:19:08 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 10:19:08 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 10:19:08 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 10:19:10 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmpixnnat 10:19:10 INFO - INFO - File integrity verified, renaming tmpixnnat to win32-minidump_stackwalk.exe 10:19:10 INFO - Return code: 0 10:19:10 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755 10:19:10 INFO - mkdir: C:\slave\test\build\blobber_upload_dir 10:19:10 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir 10:19:10 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe 10:19:10 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir 10:19:10 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/XujZSCqOTiqiqHTzLR6aPQ/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-034', '--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 10:19:10 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/XujZSCqOTiqiqHTzLR6aPQ/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-034 --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 10:19:10 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 10:19:10 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 10:19:10 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 10:19:10 INFO - 'COMPUTERNAME': 'T-W732-IX-034', 10:19:10 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 10:19:10 INFO - 'CYGWINBASE': 'C:\\cygwin', 10:19:10 INFO - 'DCLOCATION': 'SCL3', 10:19:10 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 10:19:10 INFO - 'FP_NO_HOST_CHECK': 'NO', 10:19:10 INFO - 'HOMEDRIVE': 'C:', 10:19:10 INFO - 'HOMEPATH': '\\Users\\cltbld', 10:19:10 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 10:19:10 INFO - 'KTS_VERSION': '1.19c', 10:19:10 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 10:19:10 INFO - 'LOGONSERVER': '\\\\T-W732-IX-034', 10:19:10 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir', 10:19:10 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe', 10:19:10 INFO - 'MONDIR': 'C:\\Monitor_config\\', 10:19:10 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 10:19:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:19:10 INFO - 'MOZ_NO_REMOTE': '1', 10:19:10 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir', 10:19:10 INFO - 'NO_EM_RESTART': '1', 10:19:10 INFO - 'NUMBER_OF_PROCESSORS': '8', 10:19:10 INFO - 'OS': 'Windows_NT', 10:19:10 INFO - 'OURDRIVE': 'C:', 10:19:10 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;', 10:19:10 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 10:19:10 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 10:19:10 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 10:19:10 INFO - 'PROCESSOR_LEVEL': '6', 10:19:10 INFO - 'PROCESSOR_REVISION': '1e05', 10:19:10 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 10:19:10 INFO - 'PROGRAMFILES': 'C:\\Program Files', 10:19:10 INFO - 'PROMPT': '$P$G', 10:19:10 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 10:19:10 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 10:19:10 INFO - 'PUBLIC': 'C:\\Users\\Public', 10:19:10 INFO - 'PWD': 'C:\\slave\\test', 10:19:10 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos', 10:19:10 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 10:19:10 INFO - 'SLAVEDIR': 'C:\\slave\\', 10:19:10 INFO - 'SYSTEMDRIVE': 'C:', 10:19:10 INFO - 'SYSTEMROOT': 'C:\\windows', 10:19:10 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:19:10 INFO - 'TEST1': 'testie', 10:19:10 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:19:10 INFO - 'USERDOMAIN': 'T-W732-IX-034', 10:19:10 INFO - 'USERNAME': 'cltbld', 10:19:10 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 10:19:10 INFO - 'WINDIR': 'C:\\windows', 10:19:10 INFO - 'WINDOWS_TRACING_FLAGS': '3', 10:19:10 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 10:19:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 10:19:10 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/XujZSCqOTiqiqHTzLR6aPQ/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-034', '--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 10:19:11 INFO - mozversion application_buildid: 20161016073001 10:19:11 INFO - mozversion application_changeset: 9079d167112122805f99f57bb8856e1b1675af0f 10:19:11 INFO - mozversion application_display_name: Nightly 10:19:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:19:11 INFO - mozversion application_name: Firefox 10:19:11 INFO - mozversion application_remotingname: firefox 10:19:11 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 10:19:11 INFO - mozversion application_vendor: Mozilla 10:19:11 INFO - mozversion application_version: 52.0a1 10:19:11 INFO - mozversion platform_buildid: 20161016073001 10:19:11 INFO - mozversion platform_changeset: 9079d167112122805f99f57bb8856e1b1675af0f 10:19:11 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 10:19:11 INFO - mozversion platform_version: 52.0a1 10:19:11 INFO - using testdate: 1476638351 10:19:11 INFO - actual date: 1476638351 10:19:11 INFO - starting webserver on 'localhost:50200' 10:19:11 INFO - SUITE-START | Running 2 tests 10:19:11 INFO - TEST-START | dromaeo_css 10:19:11 INFO - operating with platform_type : w7_ 10:19:11 INFO - Initialising browser for dromaeo_css test... 10:19:12 INFO - TEST-INFO | started process 2004 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpperefg\profile http://localhost:50200/getInfo.html) 10:19:19 INFO - PROCESS | 2004 | __metrics Screen width/height:1600/1200 10:19:19 INFO - PROCESS | 2004 | colorDepth:24 10:19:19 INFO - PROCESS | 2004 | Browser inner width/height: 1010/674 10:19:19 INFO - PROCESS | 2004 | __metrics 10:19:20 INFO - PROCESS | 2004 | 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" 10:19:20 INFO - PROCESS | 2004 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:19:21 INFO - TEST-INFO | 2004: exit 0 10:19:21 INFO - Browser initialized. 10:19:21 INFO - Running cycle 1/1 for dromaeo_css test... 10:19:21 INFO - TEST-INFO | started process 2808 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpperefg\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:19:21 INFO - PROCESS | 2808 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0 10:19:32 INFO - PROCESS | 2808 | RSS: Main: 123052032 10:19:32 INFO - PROCESS | 2808 | 10:20:23 INFO - PROCESS | 2808 | 1476638423983 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] 10:22:34 INFO - PROCESS | 2808 | Cycle 1(1): loaded http://localhost:50200/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50200/tests/dromaeo/cssquery-ext.html) 10:22:36 INFO - PROCESS | 2808 | RSS: Main: 253390848 10:22:36 INFO - PROCESS | 2808 | 10:25:39 INFO - PROCESS | 2808 | Cycle 1(1): loaded http://localhost:50200/tests/dromaeo/cssquery-ext.html (next: http://localhost:50200/tests/dromaeo/cssquery-jquery.html) 10:25:39 INFO - PROCESS | 2808 | RSS: Main: 198139904 10:25:39 INFO - PROCESS | 2808 | 10:28:41 INFO - PROCESS | 2808 | Cycle 1(1): loaded http://localhost:50200/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50200/tests/dromaeo/cssquery-mootools.html) 10:28:42 INFO - PROCESS | 2808 | RSS: Main: 142000128 10:28:42 INFO - PROCESS | 2808 | 10:31:44 INFO - PROCESS | 2808 | Cycle 1(1): loaded http://localhost:50200/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50200/tests/dromaeo/cssquery-prototype.html) 10:31:45 INFO - PROCESS | 2808 | RSS: Main: 138518528 10:31:45 INFO - PROCESS | 2808 | 10:34:47 INFO - PROCESS | 2808 | Cycle 1(1): loaded http://localhost:50200/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50200/tests/dromaeo/cssquery-yui.html) 10:34:48 INFO - PROCESS | 2808 | RSS: Main: 132055040 10:34:48 INFO - PROCESS | 2808 | 10:37:50 INFO - PROCESS | 2808 | Cycle 1(1): loaded http://localhost:50200/tests/dromaeo/cssquery-yui.html (next: http://localhost:50200/tests/dromaeo/cssquery-dojo.html) 10:37:51 INFO - PROCESS | 2808 | RSS: Main: 255643648 10:37:51 INFO - PROCESS | 2808 | 10:37:51 INFO - PROCESS | 2808 | __start_tp_report 10:37:51 INFO - PROCESS | 2808 | _x_x_mozilla_page_load 10:37:51 INFO - PROCESS | 2808 | _x_x_mozilla_page_load_details 10:37:51 INFO - PROCESS | 2808 | |i|pagename|runs| 10:37:51 INFO - PROCESS | 2808 | |0;dojo.html;2740.671119465664;2760.240933743222;2767.128354568311;2774.292555398373;2781.9443611127776;4676.345311656367;4682.836100736471;4783.043391321737;4786.56920877121;4807.6153907687385;4626.44482662081;4647.419072615923;4749.572538471537;4790.952090479094;4859.708417494953;4109.301418758818;4145.025918909054;4232.830686772525;4241.448611680477;4243.766592837393;4199.811008504625;4232.4286221360035;4241.448611680477;4247.086876321606;4251.064765751546;4168.583141685837;4224.493060832689;4227.936580951288;4245.787710614472;4253.723507971972;4050;4153.813078411479;4186.3092589722655;4216.924684205539;4263.381809637616;4078.1843631273864;4140.37894315855;4148.543660197376;4203.789810509462;4248.08666136779;4552.817887284505;4714.764261786897;4739.691920025188;4742.430908290993;4786.545278198566;4801.231802911552;4909.459959404463;4956.504349565051;5024.2965984761795;5045.243213517965;4963.851084467472;4988.176950803113;5010.298558201855;5025.120603894304;5098.337216161913;4412.624926881219;4613.930791038137;4678.555537223959;4728.905421891543;4737.952620473751;5098.872528186751;5105.081085404663;5187.610929180327;5201.219817027476;5206.297149884793;4712.363830882856;4835.468098509161;4900.705957642553;4990.5508504234795;4992.850214493571;4905.852824415273;4995.400551933792;4996.075725990703;4997.075541024922;5004.324416203839;4920.950790492121;4947.727874966839;4959.454460009396;4964.826231081895;4964.950350496521;4947.851564453073;4953.331300274489;4975.925361119586;4980.7509624518625;4991.725455099931;3176.7935084219453;3360.8655653773576;3365.209175843675;3367.915802104967;3374.392609330344;2984.1942675477935;3015.9698403016127;3050.3594245208756;3053.6640969493783;3078.076577026883;4473.7986790594505;4494.280915053576;4499.505054444008;4631.073785242965;4646.558576935186;4790.640701947368;4971.254311853251;4981.950180498221;5074.67014644047;5140.049090918192;4586.633069354443;4739.146953548395;4740.028294199681;4743.28850672395;4751.952480475151;4151.232022075926;4158.438610787505;4189.371594260885;4189.811458484375;4216.873493795191;5020.849374518771;5161.948380516297;5246.16061430177;5251.921221181609;5262.55268302191;4780.880477988078;4976.825811096594;5048.242763585345;5063.1898896176435;5075.670081444694;5159.509846564571;5163.793448262028;5197.0905091609575;5270.868228294324;5273.34083239595;4904.215325547895;4921.409430868319;4924.950750492592;4935.728534930613;4982.576480999156;4991.475895031114;5073.8731531712;5085.389753229636;5087.033463641897;5089.05852417304;4838.032393521239;4850.151223535938;4920.1389756791705;4940.555350018516;4955.2567114933045;4741.644376671695;4775.379200704026;4792.305115758265;4844.394450693663;4942.678725882806;4725.480197178239;4732.6687131900435;4738.976305118453;4740.19416699155;4747.43030836288;4813.590844778233;4857.635677324285;4861.27080937848;4868.683535570177;4869.220924652039;4804.8798780030775;4805.735684537384;4816.494268101939;4817.27740838877;4828.734419606955 10:37:51 INFO - PROCESS | 2808 | |1;ext.html;543.7199842081328;568.1392690074538;576.6424816613699;597.4891467795038;599.6671847124846;30027.24931876704;30265.394692106143;40542.59457405425;41274.380884286686;41718;41424.37863432051;42615.14769704604;42843.78578107113;42948.3557746634;43050;8286.83426331473;8386.538740369277;8511.787205319855;8522.531260780655;8532.744017679448;6934.618595977219;7090.893636595455;7101.218865924744;10103.999999999964;10306.433146176958;9823.901760982406;10081.747956301115;10346.482675866215;10400.115990140846;10438.791224175511;10388.220883433734;10438.895611043907;10457.111145552637;10464.162866970624;10472.26694131411;6858.622775747332;7033.824154396129;9839.163671087967;10138;10210.693679189637;39202.41196382028;39330.41004384936;39349.803250983845;39435.40846887299;39442.21115577701;3024.1985873743474;3124.53132030195;3169.3344397676515;3357.9496307555405;3465.6707612776745;30311;30935.535966960513;31073.689263107306;31323.686763132304;31335;3069.938601227963;3085.706857848523;3108.8290144042066;3126.0621813455873;3144.151079208601;376399;378350;380932;381286;382172.06967581756;46013.76993115013;47004;53810.192847107326;53847.34602866721;56803.33155024101;56379.436205637416;57553.13670294949;57794.7110264446;57908;57982.71008645014;14407;14701.779473307908;14755.483558075415;14829.77755333671;14835.406583736529;11108;11153.665390038312;11228.55085796559;11267.943660281646;11316;15215.782737380983;15369.231538423033;15373;15442.305096270695;17421.65156696859;6586.967065164739;6723.260441351447;6761.729530818712;6785.762498312535;6822.965885170642;868.1578868497547;875.5753459572103;886.1537231943607;915.9587818548181;938.7136923238478;28876.855615721786;29001.85499072491;29400.558991615144;29403;29573.408531829242;907.5235501361706;925.421611492817;942.6182396129578;1049.501486793782;1074.4573990135088;14855.851441485664;15228;15532.223388830513;15739.291731872094;15807.36770529166;537065;537687;541294;542006;545953;51465.7426712864;51591.48408515942;51842.74078629583;51944.74027629913;51994.74002629962;1091.8689757229183;1133.3030186435283;1147.9782993136116;1148.586508856811;1150.8446359741329;28020.43959120847;28627.427451451273;28674.139775806758;29043.419131617673;29363.85318073396;46328.305075423224;46403.76798115988;46571.91847589688;46727;46732.53267467418;464670;466882;466924;469101;469777;20295.82531082523;21124.88751124875;23525.88237058804;23693.644595330738;23862.16482423107;43541.3468797962;44189.77905110454;44440.55559444494;44465.55534444614;44552.554474456134;36763.14790013363;37352.62647373491;37647.24705505929;37898.43152352662;38942.22115557616;37452.25095498129;37707.81146094252;37893.431598525494;38090.80954595209;38403.807980959915;36692.44961325636;36847;37015.81492092522;37069;37152.256954861296;32084.679153209105;33895.27152013326;34003;34136.48795268123;34967.65032349644;25366.239012829643;26157;26236.344091397103;26311.473770524866;26352.868235658698 10:37:51 INFO - PROCESS | 2808 | |2;jquery.html;268.242215740533;285.3422860306992;288.44906229102406;293.0592797121241;299.43855271366186;13294.66763330916;17775.555611109714;18671;18734.906325468386;18747.62504749906;17236.13819309036;17493.7375939361;17673.293068277253;17816.46550603484;17962.910185449087;5196.090684130262;5203.791848326063;5374.516293533581;5375.489328513785;5382.407935127133;5153.2785410042625;5189.8183563575285;5262.4474430184855;5292.259083728281;5311.2298716686055;4938.259261110827;4946.480619534951;4954.281629163787;5034.144195486776;5253.6322457428;5022.773975171126;5144.717040562787;5238;5244.79020839166;5284.365876094855;4452.710573812692;4520.841770538015;4525.094981003813;4526.072155208208;4541.409616749835;20289.797102029086;21752.12991480339;21764.238251661114;22320.55358892813;22684.65973010406;1696.2451708989504;1715.519654496743;1735.1150913034317;1771.247219931524;2076.979230207709;8313.667453301861;8489.830203395897;8642.913570864273;12423.875761242361;13624.659383515393;1658.7594798754233;1686.1063636272795;1708.521613948084;1724.4740354191968;1733.6879361714887;60712.69643651754;88858;90867;91023.99999999933;91354;8842.115788421235;8931.374803763674;8984.8652270216;9218.911664592302;9273;10644.308119972331;10885.346879187275;11135.498902549405;11264.774704505864;11305.095592352593;6881.036654868322;7293.671784769592;7317.926820731831;7346.816329591696;7354.816129596696;6969.965150174216;6972.965135174291;7054.2240353561065;7056.823579410556;7171.820704482326;3487.6861082502696;3498.4577390504514;3631.219287853124;3748.5689145748042;3786.6213378662464;1536.6081649179387;1552.5420001099703;1579.921003949798;1598.8720902328018;1602.5512856400226;801.6753214947955;830.3772170871846;842.6334544473174;858.2361698088706;899.4378513429107;18278.725819112726;18319.267229310677;18591.25634974613;18612.534686632942;18965.715514267296;874.9737507874773;876.68001179569;890.8530092534788;908.5094049213351;956.9377990430617;9553.235741140692;9978.850317245247;10196.898031019595;10212;10326.48367581618;349700;424248;427107;429719;431547;92094;100101;102353.48823255984;110212.92669834275;114755;896.6547879066476;905.1129892705054;913.7487191022522;923.4367036107968;936.4521754773398;16507.257173427464;16654.50036498907;16946.237419315912;16981.405650802655;17376.478705638852;44396;44834;45275.320870187636;46087.76956115332;46115.30827037664;61515.692421537606;63816.36183638104;64085;64596.35403645904;65211;6665.400113989765;6910.2398736137975;6914.135733033371;6989.860202796017;7071.611061391673;9375.765605859635;12221.14451988388;12294.938525307316;12404.503819847105;12589.622311330675;10437.895621043692;10489.108425783894;10688.289873620184;10759.623413180501;10761.515731792244;9957.253206009433;10421.374717516974;10462.58149674035;10550;10869.945650272015;10795.40625265618;11220.326780393203;11273.549058037916;11316.490757916079;11343.773124537298;5789.8552536186935;6184.134221208944;6236.095766113979;6318.368163183829;6350.4919606432295;7764.92235077642;10650.627228046982;10696.197785165989;10791.514381852992;11113.110951124052 10:37:51 INFO - PROCESS | 2808 | |3;mootools.html;1086.8098082835502;1157.9108290306197;1174.983639152133;1256.849178098628;1496.8577985091413;6031.306399764023;6657.1345725055735;6765.966170169142;6832.487563432737;6840.213375461817;6713.731450741964;6768.323167683229;6835.863282734343;6871.450283977282;6884.106870610584;1813.3471950097953;1819.2086442397567;1872.3727551270272;1885.2741694447668;1887.9056047197653;1825.3337531800864;1857.6377606366764;1864.4965859218003;1871.7847447543502;1875.5123667846321;1889.2159753702197;1890.9149088290992;1893.0913161682402;1893.5928775313305;1896.1751638037429;1819.5087326421858;1843.3087592152942;1843.787964384093;1846.1599972012702;1848.0390197097568;1766.9823301766946;1808.1592059692175;1835.733818596296;1837.255911355903;1849.2787812753038;6649.5012874034155;6757.087793147961;6759.695813688396;6774.119364482585;6783.389494945479;4032.4152997815445;4039;4147.979260103711;4181.770002649853;4216.367544868263;2765.1566272286946;2805.2846524136417;2832.2919270182456;2835.957460638092;2841.801073924826;859.8065435277075;870.0255713600744;880.5949263338807;884.0408157149501;889.6308032166643;8615.396922215448;8677.132286771335;8681.696140635111;8690.174433428814;8714.869276960788;3273.8690452382116;3352.3127758809387;3379.5099710541836;3379.6789305016478;3383.509391138271;6770.390664840188;6818.488613353919;6999.335063168991;7013.649317534103;7015.403690686247;3994.800259986989;4018.6985976051915;4020.4974378202724;4026.59734026595;4034.1931613677384;3411.7441191910707;3523.9119022024142;3585.103724068983;3706.8331925063435;3750.549934007882;2795.175423250146;2912.7524160446173;2913.4027524357457;2922.503174460347;2936.4420760055523;849.2484151525821;865.3682811547479;875.5315905990265;876.8114855306139;878.4377998081233;740.1747052037048;785.1951749456853;789.5262842294577;797.7925739307822;816.7917181118777;4447.421835161408;4555.589996900295;4626.21354369758;4647.37260469839;4687.226607609706;801.0947629178991;802.3741481644327;806.6652339279134;822.5599304372132;828.7430896422128;3139.607549056368;3172.4289627867206;3234.741220702338;3245.837708114585;3294.1270563300745;7672.347850432664;8054.879176812353;8090.4336696431865;8128.065272493621;8164.428490005643;7890.132085470594;8070.314023308085;8096.5546894921345;8098.473599216032;8118.35053195743;6283.622982621057;6431.549791514549;6444.77443289492;6457.096006559085;6514.641694706742;4554.954450455453;4574.245249533789;4585.472670642919;4591.127685739699;4652.116097941381;7114.359707626339;7367.484276100621;7416.406687465098;7623.123340815655;7671.2712292332135;7435.405167586689;7715.922840771745;7734.149243583089;7735.033120859893;7756.1468238494845;5563.109902415594;5669.177969194361;5690.829275121753;5696.686682232517;5705.286839145107;6028.15605815195;6092.177556029879;6208.9689551551955;6595.736170553124;6695.296993815579;6037.577369584087;6064.211652485149;6065.939340606537;6087.847803804941;6133.478654314434;5946;5948.345681975065;5968.074948383008;6009.188759517409;6014.0678194879865;5392.164214546751;5477.863053423696;5481.451854814646;5502.9174562382395;5567.777288908398;3857.768533887863;3906.8632597858937;4037.4347591337823;4057.5739547348285;4064.9593504064583;3959.7822119783687;3991.6207960243732;4078.7348822326453;4081.2041651878785;4102.958970410377 10:37:51 INFO - PROCESS | 2808 | |4;prototype.html;231.86435934978036;232.63360207672915;241.02625393410622;242.8858436534829;244.61228952110903;4955.752212389375;5090.796368145275;5113.923291150636;5118.2322651602235;5133.022846748886;4950.15847305958;4973.52751488608;5008.173651347523;5011.649184557083;5049.065922804284;3666.4866918631415;3674.2284120334693;3676.834542445583;3678.1172518595477;3690.8892733217913;3595.3168897909313;3624.9456258156015;3678.981605091971;3687.2441149564274;3697.7982155799336;3437.484377343406;3468.8146467725423;3552.520409744677;3554.235839294551;3570.250247448039;3465.9133521662034;3631.5642122945414;3640.799756013418;3641.1443310821937;3664.303782281358;3008.7592992560544;3131.48330525463;3136.5452009458727;3143.669914658951;3147.4491963906453;4726.550977657152;4953;4960.603151747888;4965.4041515018425;4972.8756781080765;4051.534073581546;4093.44738460307;4261.786910654486;4262.7868606569855;4305.375720520538;5075.5178258065425;5213.530782229617;5221.190715439113;5222.608304377187;5229.555487783543;3496.2657841853243;3496.912577185565;3499.7550171488006;3504.088936876405;3509.929801403983;5477.534409575191;5498.065328894098;5500.724963751796;5501.377093757351;5503.981763373788;3476.7044801192183;3564.1267889367273;3580.6419358064504;3590.8743193988084;3591.868561403202;3531.805750683686;3613.801240931774;3619.4570814378053;3629.9818500907854;3641.8361173747244;4236.639885609758;4258.083862261995;4283.678724095706;4284.507281662586;4286.249906266391;5190.506901844319;5248.763805628756;5283.313169288045;5292.947070529245;5293.602979776455;1250.1436515986397;1258.1256027061288;1262.3814330977755;1263.759885621729;1265.240855486719;763.152900280688;776.5030380556449;776.9961150194326;778.2917545033991;796.0964305513169;4507.323901414814;4999.47505511916;5008.799648014038;5113.795448182031;5119.718415487109;5539;5564.471375219428;5564.777408903598;5567.164925261243;5579.38626751057;5031.396232452129;5089;5091.861418204363;5094.923576146361;5112.539871411516;2841.4743272494657;2867.6845546989816;2869.354395261091;2894.1172942252815;2942.80871743336;5651.717414129359;5735.254416925773;5741.483266506035;5748.7125643717645;5753.88492230153;5600.691961942132;5608.812563100394;5616.410276920946;5617.185508101303;5650.604457688005;5706.572007099484;5716.314042314865;5719.399463056319;5728;5742.827715168552;5503.11950087984;5503.559715222853;5503.697296648723;5516.834494965157;5517.531009864046;5358.223057656696;5366.946330536644;5436.266104076058;5441.782328706808;5443.836684899459;5584.916226256528;5725.141228815711;5732.738797464551;5734.139879018181;5748.712564371681;3053.7098975597282;3466.947995780015;3487.145649315934;3492.353914525821;3496.6153723089933;5359.598030147677;5476.959377718221;5479.465749590121;5498.728256687265;5501.779928802803;5323.441038690882;5324.547413469899;5329.866753331043;5334.359876814807;5348.315280686571;5150.613703972292;5262.868428289324;5321.9467805323;5322.920156197582;5327.653702509325;5107.821226257063;5220.477952204749;5239.685618862881;5247.475252474722;5248;5150.845474635767;5228.085085110014;5294.98186033957;5364.792921592611;5371.785128594813;5022.070916880389;5299.602529810357;5324.7870085196155;5334.306540149756;5337.252784610236 10:37:51 INFO - PROCESS | 2808 | |5;yui.html;639.5107742576929;705.7812078255741;715.4920006795176;715.906932098827;725.6371814092954;15164.241787910601;15892.602684932868;15900.966437181589;16507.422240221596;17287.394941177063;15640.765388519181;15816.52550423489;15848.84151158488;15877.285522151502;15888.523344299689;522.3183745212497;646.2438946432674;662.8077857421342;666.4501786026548;666.8632930249312;533.479857139288;628.0485065126337;653.1085068880967;663.7013343995197;667.0860920538869;698.4377576051277;700.2332445971679;700.3171907390279;701.7789396340162;702.5328156775738;683.665004147969;698.7903628911332;699.3286445012794;699.828542007209;700.0514303119277;401.42195238856243;407.4987765049006;491.0694234425779;497.0952865784274;503.48141414343286;14597.927010364987;14663.70672586553;14750.631234219123;14801;14816.481423150246;12714.173578717353;12909.483620655163;12921.741565168644;13170.60488185356;13509.256990865499;12865.228086314852;13087.738245235138;13100.672483187902;13462.326883655778;14088.718225635532;4271.572842715735;4308.9138217235795;4345.217860785057;4365.890852728676;4368.453943257093;74508.62745686236;85565.57217213874;85591;86949.56525217397;87342.56328718379;10480.109190718722;10554.261201715806;10597.788044239072;10731.946340268249;10897.891021089688;18954.526136846413;18973.146208420654;19076.04619769006;19084.809151908303;19164.520886977938;7332.450066245053;7441.479096463196;7640.465167438226;7764.922350776533;7833.804154896173;4030.556638769733;4032.2540330039037;4053.189362127527;4174.102567947845;4275.0594869128745;2855.486012517725;2897.7392034716977;2919.868605912739;2998.1005698290423;3025.9848700756797;506.3796848860117;526.3604720264897;537.9300690910156;540.5837505121035;542.5849225342616;607.9544034197453;683.2723149845399;695.5027155583681;715.492000679513;719.0759873562447;9621.567029483691;9658.90341096594;9781.902180978099;9924.65263715781;10037.447940363205;627.1220291591717;682.1847891769387;708.4084789200972;714.2536049827942;723.1466869094519;4819.469858315582;5078.568321692697;5103.18349064148;5128.794848206105;5130.169215005542;72975.90536141962;113639;115247;117584;123583;85064;85119;97847.51076244573;111120.88879111267;111615.44192278986;91421;95443;96043;97099.51450242844;98601;9635.373700709431;9739.951300243454;9766.46284454362;9860.359076659994;9952.651657191964;40947;50210.497895020584;50770.74614626903;52365.21452178297;54511.18233226425;77280;77728.61135694286;79762;80426.59786701029;82971.5851420739;12860;12914.418851151533;13018.414171362121;13421.396037178156;13459.327033648082;15374.461893833663;15593.454229101926;15710.607234819221;15862.841371586137;16525.173741312647;14485.565433037025;14548.927255363655;14780.113193208443;14850;15171.393144274105;13911.513097041556;13981.44074237019;14199.006069575444;14319.642008949859;14629.634259143606;13446.389496717802;13857.445702172203;14157.646058848612;14391.496297629532;14678.339474723449;11267.211295209258;11429.657110286706;11657.53369865196;11973.54056896734;12312.630621081382;11721.941390292994;11812.291262524275;11946.163768536466;12129.575464858688;12191.390430478617 10:37:51 INFO - PROCESS | 2808 | __end_tp_report 10:37:51 INFO - PROCESS | 2808 | __start_cc_report 10:37:51 INFO - PROCESS | 2808 | _x_x_mozilla_cycle_collect,1989 10:37:51 INFO - PROCESS | 2808 | __end_cc_report 10:37:51 INFO - PROCESS | 2808 | __startTimestamp1476639471165__endTimestamp 10:37:51 INFO - PROCESS | 2808 | 10:37:51 INFO - PROCESS | 2808 | ------- Summary: start ------- 10:37:51 INFO - PROCESS | 2808 | Number of tests: 6 10:37:51 INFO - PROCESS | 2808 | 10:37:51 INFO - PROCESS | 2808 | [#0] dojo.html Cycles:165 Average:4600.20 Median:4790.95 stddev:587.80 (12.3%) stddev-sans-first:571.22 10:37:51 INFO - PROCESS | 2808 | Values: 2740.7 2760.2 2767.1 2774.3 2781.9 4676.3 4682.8 4783.0 4786.6 4807.6 4626.4 4647.4 4749.6 4791.0 4859.7 4109.3 4145.0 4232.8 4241.4 4243.8 4199.8 4232.4 4241.4 4247.1 4251.1 4168.6 4224.5 4227.9 4245.8 4253.7 4050.0 4153.8 4186.3 4216.9 4263.4 4078.2 4140.4 4148.5 4203.8 4248.1 4552.8 4714.8 4739.7 4742.4 4786.5 4801.2 4909.5 4956.5 5024.3 5045.2 4963.9 4988.2 5010.3 5025.1 5098.3 4412.6 4613.9 4678.6 4728.9 4738.0 5098.9 5105.1 5187.6 5201.2 5206.3 4712.4 4835.5 4900.7 4990.6 4992.9 4905.9 4995.4 4996.1 4997.1 5004.3 4921.0 4947.7 4959.5 4964.8 4965.0 4947.9 4953.3 4975.9 4980.8 4991.7 3176.8 3360.9 3365.2 3367.9 3374.4 2984.2 3016.0 3050.4 3053.7 3078.1 4473.8 4494.3 4499.5 4631.1 4646.6 4790.6 4971.3 4982.0 5074.7 5140.0 4586.6 4739.1 4740.0 4743.3 4752.0 4151.2 4158.4 4189.4 4189.8 4216.9 5020.8 5161.9 5246.2 5251.9 5262.6 4780.9 4976.8 5048.2 5063.2 5075.7 5159.5 5163.8 5197.1 5270.9 5273.3 4904.2 4921.4 4925.0 4935.7 4982.6 4991.5 5073.9 5085.4 5087.0 5089.1 4838.0 4850.2 4920.1 4940.6 4955.3 4741.6 4775.4 4792.3 4844.4 4942.7 4725.5 4732.7 4739.0 4740.2 4747.4 4813.6 4857.6 4861.3 4868.7 4869.2 4804.9 4805.7 4816.5 4817.3 4828.7 10:37:51 INFO - PROCESS | 2808 | 10:37:51 INFO - PROCESS | 2808 | [#1] ext.html Cycles:180 Average:60065.67 Median:207023.12 stddev:124337.58 (60.1%) stddev-sans-first:124606.07 10:37:51 INFO - PROCESS | 2808 | Values: 543.7 568.1 576.6 597.5 599.7 30027.2 30265.4 40542.6 41274.4 41718.0 41424.4 42615.1 42843.8 42948.4 43050.0 8286.8 8386.5 8511.8 8522.5 8532.7 6934.6 7090.9 7101.2 10104.0 10306.4 9823.9 10081.7 10346.5 10400.1 10438.8 10388.2 10438.9 10457.1 10464.2 10472.3 6858.6 7033.8 9839.2 10138.0 10210.7 39202.4 39330.4 39349.8 39435.4 39442.2 3024.2 3124.5 3169.3 3357.9 3465.7 30311.0 30935.5 31073.7 31323.7 31335.0 3069.9 3085.7 3108.8 3126.1 3144.2 376399.0 378350.0 380932.0 381286.0 382172.1 46013.8 47004.0 53810.2 53847.3 56803.3 56379.4 57553.1 57794.7 57908.0 57982.7 14407.0 14701.8 14755.5 14829.8 14835.4 11108.0 11153.7 11228.6 11267.9 11316.0 15215.8 15369.2 15373.0 15442.3 17421.7 6587.0 6723.3 6761.7 6785.8 6823.0 868.2 875.6 886.2 916.0 938.7 28876.9 29001.9 29400.6 29403.0 29573.4 907.5 925.4 942.6 1049.5 1074.5 14855.9 15228.0 15532.2 15739.3 15807.4 537065.0 537687.0 541294.0 542006.0 545953.0 51465.7 51591.5 51842.7 51944.7 51994.7 1091.9 1133.3 1148.0 1148.6 1150.8 28020.4 28627.4 28674.1 29043.4 29363.9 46328.3 46403.8 46571.9 46727.0 46732.5 464670.0 466882.0 466924.0 469101.0 469777.0 20295.8 21124.9 23525.9 23693.6 23862.2 43541.3 44189.8 44440.6 44465.6 44552.6 36763.1 37352.6 37647.2 37898.4 38942.2 37452.3 37707.8 37893.4 38090.8 38403.8 36692.4 36847.0 37015.8 37069.0 37152.3 32084.7 33895.3 34003.0 34136.5 34967.7 25366.2 26157.0 26236.3 26311.5 26352.9 10:37:51 INFO - PROCESS | 2808 | 10:37:51 INFO - PROCESS | 2808 | [#2] jquery.html Cycles:180 Average:26705.74 Median:237971.50 stddev:69475.95 (29.2%) stddev-sans-first:69642.49 10:37:51 INFO - PROCESS | 2808 | Values: 268.2 285.3 288.4 293.1 299.4 13294.7 17775.6 18671.0 18734.9 18747.6 17236.1 17493.7 17673.3 17816.5 17962.9 5196.1 5203.8 5374.5 5375.5 5382.4 5153.3 5189.8 5262.4 5292.3 5311.2 4938.3 4946.5 4954.3 5034.1 5253.6 5022.8 5144.7 5238.0 5244.8 5284.4 4452.7 4520.8 4525.1 4526.1 4541.4 20289.8 21752.1 21764.2 22320.6 22684.7 1696.2 1715.5 1735.1 1771.2 2077.0 8313.7 8489.8 8642.9 12423.9 13624.7 1658.8 1686.1 1708.5 1724.5 1733.7 60712.7 88858.0 90867.0 91024.0 91354.0 8842.1 8931.4 8984.9 9218.9 9273.0 10644.3 10885.3 11135.5 11264.8 11305.1 6881.0 7293.7 7317.9 7346.8 7354.8 6970.0 6973.0 7054.2 7056.8 7171.8 3487.7 3498.5 3631.2 3748.6 3786.6 1536.6 1552.5 1579.9 1598.9 1602.6 801.7 830.4 842.6 858.2 899.4 18278.7 18319.3 18591.3 18612.5 18965.7 875.0 876.7 890.9 908.5 956.9 9553.2 9978.9 10196.9 10212.0 10326.5 349700.0 424248.0 427107.0 429719.0 431547.0 92094.0 100101.0 102353.5 110212.9 114755.0 896.7 905.1 913.7 923.4 936.5 16507.3 16654.5 16946.2 16981.4 17376.5 44396.0 44834.0 45275.3 46087.8 46115.3 61515.7 63816.4 64085.0 64596.4 65211.0 6665.4 6910.2 6914.1 6989.9 7071.6 9375.8 12221.1 12294.9 12404.5 12589.6 10437.9 10489.1 10688.3 10759.6 10761.5 9957.3 10421.4 10462.6 10550.0 10869.9 10795.4 11220.3 11273.5 11316.5 11343.8 5789.9 6184.1 6236.1 6318.4 6350.5 7764.9 10650.6 10696.2 10791.5 11113.1 10:37:51 INFO - PROCESS | 2808 | 10:37:51 INFO - PROCESS | 2808 | [#3] mootools.html Cycles:180 Average:4389.13 Median:5618.48 stddev:2395.19 (42.6%) stddev-sans-first:2389.05 10:37:51 INFO - PROCESS | 2808 | Values: 1086.8 1157.9 1175.0 1256.8 1496.9 6031.3 6657.1 6766.0 6832.5 6840.2 6713.7 6768.3 6835.9 6871.5 6884.1 1813.3 1819.2 1872.4 1885.3 1887.9 1825.3 1857.6 1864.5 1871.8 1875.5 1889.2 1890.9 1893.1 1893.6 1896.2 1819.5 1843.3 1843.8 1846.2 1848.0 1767.0 1808.2 1835.7 1837.3 1849.3 6649.5 6757.1 6759.7 6774.1 6783.4 4032.4 4039.0 4148.0 4181.8 4216.4 2765.2 2805.3 2832.3 2836.0 2841.8 859.8 870.0 880.6 884.0 889.6 8615.4 8677.1 8681.7 8690.2 8714.9 3273.9 3352.3 3379.5 3379.7 3383.5 6770.4 6818.5 6999.3 7013.6 7015.4 3994.8 4018.7 4020.5 4026.6 4034.2 3411.7 3523.9 3585.1 3706.8 3750.5 2795.2 2912.8 2913.4 2922.5 2936.4 849.2 865.4 875.5 876.8 878.4 740.2 785.2 789.5 797.8 816.8 4447.4 4555.6 4626.2 4647.4 4687.2 801.1 802.4 806.7 822.6 828.7 3139.6 3172.4 3234.7 3245.8 3294.1 7672.3 8054.9 8090.4 8128.1 8164.4 7890.1 8070.3 8096.6 8098.5 8118.4 6283.6 6431.5 6444.8 6457.1 6514.6 4555.0 4574.2 4585.5 4591.1 4652.1 7114.4 7367.5 7416.4 7623.1 7671.3 7435.4 7715.9 7734.1 7735.0 7756.1 5563.1 5669.2 5690.8 5696.7 5705.3 6028.2 6092.2 6209.0 6595.7 6695.3 6037.6 6064.2 6065.9 6087.8 6133.5 5946.0 5948.3 5968.1 6009.2 6014.1 5392.2 5477.9 5481.5 5502.9 5567.8 3857.8 3906.9 4037.4 4057.6 4065.0 3959.8 3991.6 4078.7 4081.2 4103.0 10:37:51 INFO - PROCESS | 2808 | 10:37:51 INFO - PROCESS | 2808 | [#4] prototype.html Cycles:180 Average:4370.35 Median:5101.37 stddev:1395.85 (27.4%) stddev-sans-first:1364.77 10:37:51 INFO - PROCESS | 2808 | Values: 231.9 232.6 241.0 242.9 244.6 4955.8 5090.8 5113.9 5118.2 5133.0 4950.2 4973.5 5008.2 5011.6 5049.1 3666.5 3674.2 3676.8 3678.1 3690.9 3595.3 3624.9 3679.0 3687.2 3697.8 3437.5 3468.8 3552.5 3554.2 3570.3 3465.9 3631.6 3640.8 3641.1 3664.3 3008.8 3131.5 3136.5 3143.7 3147.4 4726.6 4953.0 4960.6 4965.4 4972.9 4051.5 4093.4 4261.8 4262.8 4305.4 5075.5 5213.5 5221.2 5222.6 5229.6 3496.3 3496.9 3499.8 3504.1 3509.9 5477.5 5498.1 5500.7 5501.4 5504.0 3476.7 3564.1 3580.6 3590.9 3591.9 3531.8 3613.8 3619.5 3630.0 3641.8 4236.6 4258.1 4283.7 4284.5 4286.2 5190.5 5248.8 5283.3 5292.9 5293.6 1250.1 1258.1 1262.4 1263.8 1265.2 763.2 776.5 777.0 778.3 796.1 4507.3 4999.5 5008.8 5113.8 5119.7 5539.0 5564.5 5564.8 5567.2 5579.4 5031.4 5089.0 5091.9 5094.9 5112.5 2841.5 2867.7 2869.4 2894.1 2942.8 5651.7 5735.3 5741.5 5748.7 5753.9 5600.7 5608.8 5616.4 5617.2 5650.6 5706.6 5716.3 5719.4 5728.0 5742.8 5503.1 5503.6 5503.7 5516.8 5517.5 5358.2 5366.9 5436.3 5441.8 5443.8 5584.9 5725.1 5732.7 5734.1 5748.7 3053.7 3466.9 3487.1 3492.4 3496.6 5359.6 5477.0 5479.5 5498.7 5501.8 5323.4 5324.5 5329.9 5334.4 5348.3 5150.6 5262.9 5321.9 5322.9 5327.7 5107.8 5220.5 5239.7 5247.5 5248.0 5150.8 5228.1 5295.0 5364.8 5371.8 5022.1 5299.6 5324.8 5334.3 5337.3 10:37:51 INFO - PROCESS | 2808 | 10:37:51 INFO - PROCESS | 2808 | [#5] yui.html Cycles:180 Average:21214.04 Median:4291.99 stddev:30809.96 (717.8%) stddev-sans-first:30857.66 10:37:51 INFO - PROCESS | 2808 | Values: 639.5 705.8 715.5 715.9 725.6 15164.2 15892.6 15901.0 16507.4 17287.4 15640.8 15816.5 15848.8 15877.3 15888.5 522.3 646.2 662.8 666.5 666.9 533.5 628.0 653.1 663.7 667.1 698.4 700.2 700.3 701.8 702.5 683.7 698.8 699.3 699.8 700.1 401.4 407.5 491.1 497.1 503.5 14597.9 14663.7 14750.6 14801.0 14816.5 12714.2 12909.5 12921.7 13170.6 13509.3 12865.2 13087.7 13100.7 13462.3 14088.7 4271.6 4308.9 4345.2 4365.9 4368.5 74508.6 85565.6 85591.0 86949.6 87342.6 10480.1 10554.3 10597.8 10731.9 10897.9 18954.5 18973.1 19076.0 19084.8 19164.5 7332.5 7441.5 7640.5 7764.9 7833.8 4030.6 4032.3 4053.2 4174.1 4275.1 2855.5 2897.7 2919.9 2998.1 3026.0 506.4 526.4 537.9 540.6 542.6 608.0 683.3 695.5 715.5 719.1 9621.6 9658.9 9781.9 9924.7 10037.4 627.1 682.2 708.4 714.3 723.1 4819.5 5078.6 5103.2 5128.8 5130.2 72975.9 113639.0 115247.0 117584.0 123583.0 85064.0 85119.0 97847.5 111120.9 111615.4 91421.0 95443.0 96043.0 97099.5 98601.0 9635.4 9740.0 9766.5 9860.4 9952.7 40947.0 50210.5 50770.7 52365.2 54511.2 77280.0 77728.6 79762.0 80426.6 82971.6 12860.0 12914.4 13018.4 13421.4 13459.3 15374.5 15593.5 15710.6 15862.8 16525.2 14485.6 14548.9 14780.1 14850.0 15171.4 13911.5 13981.4 14199.0 14319.6 14629.6 13446.4 13857.4 14157.6 14391.5 14678.3 11267.2 11429.7 11657.5 11973.5 12312.6 11721.9 11812.3 11946.2 12129.6 12191.4 10:37:51 INFO - PROCESS | 2808 | -------- Summary: end -------- 10:37:51 INFO - PROCESS | 2808 | 10:37:51 INFO - TEST-INFO | 2808: exit 0 10:37:52 INFO - TEST-OK | dromaeo_css | took 1120498ms 10:37:52 INFO - TEST-START | kraken 10:37:52 INFO - operating with platform_type : w7_ 10:37:52 INFO - Initialising browser for kraken test... 10:37:52 INFO - TEST-INFO | started process 196 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmp7mmq8v\profile http://localhost:50200/getInfo.html) 10:37:56 INFO - PROCESS | 196 | __metrics Screen width/height:1600/1200 10:37:56 INFO - PROCESS | 196 | colorDepth:24 10:37:56 INFO - PROCESS | 196 | Browser inner width/height: 1010/674 10:37:56 INFO - PROCESS | 196 | __metrics 10:37:57 INFO - PROCESS | 196 | 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" 10:37:57 INFO - PROCESS | 196 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:37:57 INFO - PROCESS | 196 | console.error: 10:37:57 INFO - PROCESS | 196 | Message: Error: Connection closed before committing the transaction. 10:37:57 INFO - PROCESS | 196 | Stack: 10:37:57 INFO - PROCESS | 196 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:937:23 10:37:57 INFO - PROCESS | 196 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 10:37:57 INFO - PROCESS | 196 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 10:37:57 INFO - PROCESS | 196 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 10:37:57 INFO - PROCESS | 196 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 10:37:57 INFO - PROCESS | 196 | TaskImpl_run@resource://gre/modules/Task.jsm:327:15 10:37:57 INFO - PROCESS | 196 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9 10:37:57 INFO - PROCESS | 196 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 10:37:57 INFO - PROCESS | 196 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5 10:37:57 INFO - PROCESS | 196 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 10:37:57 INFO - PROCESS | 196 | Barrier/this.client.addBlocker/promise13.1% 10:38:55 INFO - TinderboxPrint: I/O read bytes / time
80,232,448 / 857,950 10:38:55 INFO - TinderboxPrint: I/O write bytes / time
456,286,208 / 3,927,510 10:38:55 INFO - TinderboxPrint: CPU idle
8,276.0 (86.9%) 10:38:55 INFO - TinderboxPrint: CPU user
1,209.7 (12.7%) 10:38:55 INFO - install - Wall time: 4s; CPU: 8.0%; Read bytes: 49152; Write bytes: 70784512; Read time: 3360; Write time: 516020 10:38:55 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 80183296; Write bytes: 381180928; Read time: 854590; Write time: 2786980 10:38:55 INFO - Running post-run listener: _upload_blobber_files 10:38:55 INFO - Blob upload gear active. 10:38:55 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir. 10:38:55 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 10:38:55 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'] 10:38:55 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 10:38:56 INFO - (blobuploader) - INFO - Open directory for files ... 10:38:56 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log ... 10:38:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:38:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:38:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:38:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:38:57 INFO - (blobuploader) - INFO - Done attempting. 10:38:57 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log ... 10:38:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:38:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:38:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:38:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:38:58 INFO - (blobuploader) - INFO - Done attempting. 10:38:58 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ... 10:38:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:38:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:39:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:39:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:39:05 INFO - (blobuploader) - INFO - Done attempting. 10:39:05 INFO - (blobuploader) - INFO - Iteration through files over. 10:39:05 INFO - Return code: 0 10:39:05 INFO - rmtree: C:\slave\test\build\uploaded_files.json 10:39:05 INFO - Using _rmtree_windows ... 10:39:05 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1 10:39:05 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a28cbefbf432baaea0e3bb13e0ae39ef7fa8c3f80fe585c5a609dee97de02d37de5407b44f13246e0386a7a754776fc169f606c9fd0ef3feafee22f5b2c34ef4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b46440a789f3131fa62f01e7834502c2180119f90498c96cd754230cb07d7a69d387949e992c767fe128e5f76f825aeec7fcac539272eb7be19a59b384826b10", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/af614c8e402dfe6429d80efe6c09c3114b23bbfda3a3020782f61394fdfe255eaca68f4720824756bd9f2257a0e57329d801188a0a13a3c651344b83f5a4d7e6"} 10:39:05 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files 10:39:05 INFO - Writing to file C:\slave\test\properties\blobber_files 10:39:05 INFO - Contents: 10:39:05 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a28cbefbf432baaea0e3bb13e0ae39ef7fa8c3f80fe585c5a609dee97de02d37de5407b44f13246e0386a7a754776fc169f606c9fd0ef3feafee22f5b2c34ef4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b46440a789f3131fa62f01e7834502c2180119f90498c96cd754230cb07d7a69d387949e992c767fe128e5f76f825aeec7fcac539272eb7be19a59b384826b10", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/af614c8e402dfe6429d80efe6c09c3114b23bbfda3a3020782f61394fdfe255eaca68f4720824756bd9f2257a0e57329d801188a0a13a3c651344b83f5a4d7e6"} 10:39:05 INFO - Running post-run listener: copy_logs_to_upload_dir 10:39:05 INFO - Copying logs to upload dir... 10:39:05 INFO - mkdir: C:\slave\test\build\upload\logs 10:39:05 INFO - Copying logs to upload dir... 10:39:05 INFO - Using _rmtree_windows ... 10:39:05 INFO - Using _rmtree_windows ... 10:39:05 INFO - Using _rmtree_windows ... 10:39:05 INFO - Using _rmtree_windows ... 10:39:05 INFO - Using _rmtree_windows ... 10:39:05 INFO - Using _rmtree_windows ... 10:39:05 INFO - Using _rmtree_windows ... program finished with exit code 0 elapsedTime=1465.171000 ========= master_lag: 0.10 ========= ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 25 secs) (at 2016-10-16 10:39:06.046290) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-16 10:39:06.046933) ========= '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-034 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-034 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-034 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/a28cbefbf432baaea0e3bb13e0ae39ef7fa8c3f80fe585c5a609dee97de02d37de5407b44f13246e0386a7a754776fc169f606c9fd0ef3feafee22f5b2c34ef4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b46440a789f3131fa62f01e7834502c2180119f90498c96cd754230cb07d7a69d387949e992c767fe128e5f76f825aeec7fcac539272eb7be19a59b384826b10", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/af614c8e402dfe6429d80efe6c09c3114b23bbfda3a3020782f61394fdfe255eaca68f4720824756bd9f2257a0e57329d801188a0a13a3c651344b83f5a4d7e6"} build_url:https://queue.taskcluster.net/v1/task/XujZSCqOTiqiqHTzLR6aPQ/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/XujZSCqOTiqiqHTzLR6aPQ/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/a28cbefbf432baaea0e3bb13e0ae39ef7fa8c3f80fe585c5a609dee97de02d37de5407b44f13246e0386a7a754776fc169f606c9fd0ef3feafee22f5b2c34ef4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b46440a789f3131fa62f01e7834502c2180119f90498c96cd754230cb07d7a69d387949e992c767fe128e5f76f825aeec7fcac539272eb7be19a59b384826b10", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/af614c8e402dfe6429d80efe6c09c3114b23bbfda3a3020782f61394fdfe255eaca68f4720824756bd9f2257a0e57329d801188a0a13a3c651344b83f5a4d7e6"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-16 10:39:06.159902) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-16 10:39:06.160218) ========= '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-034 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-034 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-034 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-16 10:39:06.270629) ========= ========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-10-16 10:39:06.270923) ========= ========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-10-16 10:39:06.724354) ========= ========= Total master_lag: 0.28 =========