builder: mozilla-central_win7_ix_test-dromaeojs-pgo slave: t-w732-ix-107 starttime: 1476984232.69 results: success (0) buildid: 20161020073000 builduid: f06e5807081f4030a6c7167d41039b60 revision: b04e95606673fff324ef0ddb09de846f7b7eaf82 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-20 10:23:52.690672) ========= master: http://buildbot-master110.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-20 10:23:52.691104) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-20 10:23:52.691407) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-20 10:23:52.842778) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-20 10:23:52.843148) ========= '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-107 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-107 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-107 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:23:54-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py => `archiver_client.py' Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. WARNING: Certificate verification error for hg.mozilla.org: certificate signature failure HTTP request sent, awaiting response... 200 Script output follows Length: 12,179 (12K) [text/x-python] 0K .......... . 100% 8.64 MB/s 10:23:55 (8.64 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=1.149000 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-20 10:23:54.017064) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 15 secs) (at 2016-10-20 10:23:54.017454) ========= '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-107 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-107 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-107 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=15.801000 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 15 secs) (at 2016-10-20 10:24:09.876496) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-20 10:24:09.876860) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo mozilla-central --rev b04e95606673fff324ef0ddb09de846f7b7eaf82 --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 b04e95606673fff324ef0ddb09de846f7b7eaf82 --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-107 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-107 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-107 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False 2016-10-20 10:24:11,410 truncating revision to first 12 chars 2016-10-20 10:24:11,410 Setting DEBUG logging. 2016-10-20 10:24:11,410 attempt 1/10 2016-10-20 10:24:11,410 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b04e95606673?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-20 10:24:11,907 unpacking tar archive at: mozilla-central-b04e95606673/testing/mozharness/ 2016-10-20 10:24:12,026 skipping symlink on windows: configs/single_locale/linux32.py program finished with exit code 0 elapsedTime=1.268000 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-20 10:24:11.191742) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-20 10:24:11.192068) ========= script_repo_revision: b04e95606673fff324ef0ddb09de846f7b7eaf82 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-20 10:24:11.192485) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-20 10:24:11.192754) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-20 10:24:11.202539) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 25 mins, 0 secs) (at 2016-10-20 10:24:11.202819) ========= '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-107 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-107 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-107 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:24:12 INFO - MultiFileLogger online at 20161020 10:24:12 in C:\slave\test 10:24:12 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:24:12 INFO - Dumping config to C:\slave\test\logs\localconfig.json. 10:24:12 INFO - {'append_to_log': False, 10:24:12 INFO - 'base_work_dir': 'C:\\slave\\test', 10:24:12 INFO - 'blob_upload_branch': 'Firefox', 10:24:12 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt', 10:24:12 INFO - 'branch': 'Firefox', 10:24:12 INFO - 'buildbot_json_path': 'buildprops.json', 10:24:12 INFO - 'config_files': ('talos/windows_config.py',), 10:24:12 INFO - 'default_actions': ('clobber', 10:24:12 INFO - 'read-buildbot-config', 10:24:12 INFO - 'download-and-extract', 10:24:12 INFO - 'populate-webroot', 10:24:12 INFO - 'create-virtualenv', 10:24:12 INFO - 'install', 10:24:12 INFO - 'run-tests'), 10:24:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:24:12 INFO - 'download_minidump_stackwalk': True, 10:24:12 INFO - 'download_symbols': 'ondemand', 10:24:12 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python', 10:24:12 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'), 10:24:12 INFO - 'hg': 'c:/mozilla-build/hg/hg', 10:24:12 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python', 10:24:12 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'), 10:24:12 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 10:24:12 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 10:24:12 INFO - 'C:/mozilla-build/tooltool.py'), 10:24:12 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 10:24:12 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 10:24:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:24:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:24:12 INFO - 'installer_path': 'installer.exe', 10:24:12 INFO - 'log_level': 'info', 10:24:12 INFO - 'log_name': 'talos', 10:24:12 INFO - 'log_to_console': True, 10:24:12 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 10:24:12 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 10:24:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 10:24:12 INFO - 'opt_config_files': (), 10:24:12 INFO - 'pip_index': False, 10:24:12 INFO - 'sps_profile': False, 10:24:12 INFO - 'sps_profile_interval': 0, 10:24:12 INFO - 'suite': 'dromaeojs', 10:24:12 INFO - 'system_bits': '32', 10:24:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:24:12 INFO - 'title': 'T-W732-IX-107', 10:24:12 INFO - 'use_talos_json': True, 10:24:12 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 10:24:12 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv', 10:24:12 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 10:24:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:24:12 INFO - 'work_dir': 'build'} 10:24:12 INFO - [mozharness: 2016-10-20 17:24:12.981000Z] Running clobber step. 10:24:12 INFO - Running pre-action listener: _resource_record_pre_action 10:24:12 INFO - Running main action method: clobber 10:24:12 INFO - rmtree: C:\slave\test\build 10:24:12 INFO - Using _rmtree_windows ... 10:24:13 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1 10:27:20 INFO - Running post-action listener: _resource_record_post_action 10:27:20 INFO - [mozharness: 2016-10-20 17:27:20.361000Z] Finished clobber step (success) 10:27:20 INFO - [mozharness: 2016-10-20 17:27:20.361000Z] Running read-buildbot-config step. 10:27:20 INFO - Running pre-action listener: _resource_record_pre_action 10:27:20 INFO - Running main action method: read_buildbot_config 10:27:20 INFO - Using buildbot properties: 10:27:20 INFO - { 10:27:20 INFO - "project": "", 10:27:20 INFO - "product": "firefox", 10:27:20 INFO - "script_repo_revision": "production", 10:27:20 INFO - "builddir": "mozilla-central_win7_ix_test-dromaeojs", 10:27:20 INFO - "repository": "", 10:27:20 INFO - "buildername": "Windows 7 32-bit mozilla-central pgo talos dromaeojs", 10:27:20 INFO - "buildid": "20161020073000", 10:27:20 INFO - "pgo_build": "True", 10:27:20 INFO - "basedir": "C:\\slave\\test", 10:27:20 INFO - "buildnumber": 56, 10:27:20 INFO - "slavename": "t-w732-ix-107", 10:27:20 INFO - "master": "http://buildbot-master110.bb.releng.scl3.mozilla.com:8201/", 10:27:20 INFO - "platform": "win7_ix", 10:27:20 INFO - "scheduler": "tests-mozilla-central-win32-pgo-talos", 10:27:20 INFO - "branch": "mozilla-central", 10:27:20 INFO - "repo_path": "mozilla-central", 10:27:20 INFO - "revision": "b04e95606673fff324ef0ddb09de846f7b7eaf82", 10:27:20 INFO - "stage_platform": "win32-pgo", 10:27:20 INFO - "builduid": "f06e5807081f4030a6c7167d41039b60", 10:27:20 INFO - "slavebuilddir": "test" 10:27:20 INFO - } 10:27:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/K0tqtoNwRYeAb-YN4Y3fRQ/artifacts/public/build/firefox-52.0a1.en-US.win32.zip. 10:27:20 INFO - Running post-action listener: _resource_record_post_action 10:27:20 INFO - [mozharness: 2016-10-20 17:27:20.365000Z] Finished read-buildbot-config step (success) 10:27:20 INFO - [mozharness: 2016-10-20 17:27:20.365000Z] Running download-and-extract step. 10:27:20 INFO - Running pre-action listener: _resource_record_pre_action 10:27:20 INFO - Running main action method: download_and_extract 10:27:20 INFO - mkdir: C:\slave\test\build\tests 10:27:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:27:20 INFO - trying https://queue.taskcluster.net/v1/task/K0tqtoNwRYeAb-YN4Y3fRQ/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json 10:27:20 INFO - Downloading https://queue.taskcluster.net/v1/task/K0tqtoNwRYeAb-YN4Y3fRQ/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:27:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/K0tqtoNwRYeAb-YN4Y3fRQ/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:27:21 INFO - Downloaded 1218 bytes. 10:27:21 INFO - Reading from file C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json 10:27:21 INFO - Using the following test package requirements: 10:27:21 INFO - {u'common': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 10:27:21 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 10:27:21 INFO - u'firefox-52.0a1.en-US.win32.cppunittest.tests.zip'], 10:27:21 INFO - u'gtest': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 10:27:21 INFO - u'jittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 10:27:21 INFO - u'jsshell-win32.zip'], 10:27:21 INFO - u'mochitest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 10:27:21 INFO - u'firefox-52.0a1.en-US.win32.mochitest.tests.zip'], 10:27:21 INFO - u'mozbase': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 10:27:21 INFO - u'reftest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 10:27:21 INFO - u'firefox-52.0a1.en-US.win32.reftest.tests.zip'], 10:27:21 INFO - u'talos': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 10:27:21 INFO - u'firefox-52.0a1.en-US.win32.talos.tests.zip'], 10:27:21 INFO - u'web-platform': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 10:27:21 INFO - u'firefox-52.0a1.en-US.win32.web-platform.tests.zip'], 10:27:21 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 10:27:21 INFO - u'firefox-52.0a1.en-US.win32.xpcshell.tests.zip']} 10:27:21 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip'] for test suite category: common 10:27:21 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/K0tqtoNwRYeAb-YN4Y3fRQ/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip 10:27:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K0tqtoNwRYeAb-YN4Y3fRQ/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1 10:27:21 INFO - Fetch https://queue.taskcluster.net/v1/task/K0tqtoNwRYeAb-YN4Y3fRQ/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory 10:27:22 INFO - Http code: 200 10:27:22 INFO - via: 1.1 9552af4ceb2e0dd55964a80d1e549fd5.cloudfront.net (CloudFront) 10:27:22 INFO - x-amz-cf-id: D29_Uxyi3kiksDim7JAps2DvAVqFMKstajQq2kaZ4SaE7_fuMVk-4A== 10:27:22 INFO - x-amz-version-id: H3nCj3d0uFO0oHX1TtQOHIESkckYf_pR 10:27:23 INFO - Expected file size: 17263059 10:27:23 INFO - Obtained file size: 17263059 10:27: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:27:50 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/K0tqtoNwRYeAb-YN4Y3fRQ/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip 10:27:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K0tqtoNwRYeAb-YN4Y3fRQ/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1 10:27:50 INFO - Fetch https://queue.taskcluster.net/v1/task/K0tqtoNwRYeAb-YN4Y3fRQ/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory 10:27:50 INFO - Http code: 200 10:27:50 INFO - via: 1.1 e2af8a85927835558866752f53562ecd.cloudfront.net (CloudFront) 10:27:50 INFO - x-amz-cf-id: PWYrictWzh42yh5c9X9U4HEdOhr5wJp82dRhHu1la-uAtbs7pAGPTQ== 10:27:50 INFO - x-amz-version-id: H3nCj3d0uFO0oHX1TtQOHIESkckYf_pR 10:27:51 INFO - Expected file size: 17263059 10:27:51 INFO - Obtained file size: 17263059 10:28:03 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/K0tqtoNwRYeAb-YN4Y3fRQ/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip 10:28:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K0tqtoNwRYeAb-YN4Y3fRQ/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip'}, attempt #1 10:28:03 INFO - Fetch https://queue.taskcluster.net/v1/task/K0tqtoNwRYeAb-YN4Y3fRQ/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip into memory 10:28:04 INFO - Http code: 200 10:28:04 INFO - via: 1.1 7f9e159e8ac7593f5d3d995abc6d2db3.cloudfront.net (CloudFront) 10:28:04 INFO - x-amz-cf-id: En0LW9UGagsBydx_Yzyy1EOV3Q5_hyJSks60Qe0GOoFrOOwn5aYpHQ== 10:28:04 INFO - x-amz-version-id: k8rL4ZTu16pWMq1007THRRaWLBAUpVAD 10:28:05 INFO - Expected file size: 14409028 10:28:05 INFO - Obtained file size: 14409028 10:28:10 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:28:10 INFO - trying https://queue.taskcluster.net/v1/task/K0tqtoNwRYeAb-YN4Y3fRQ/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 10:28:10 INFO - Downloading https://queue.taskcluster.net/v1/task/K0tqtoNwRYeAb-YN4Y3fRQ/artifacts/public/build/firefox-52.0a1.en-US.win32.zip to C:\slave\test\build\firefox-52.0a1.en-US.win32.zip 10:28:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/K0tqtoNwRYeAb-YN4Y3fRQ/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:28:11 INFO - Downloaded 54343336 bytes. 10:28:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/K0tqtoNwRYeAb-YN4Y3fRQ/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 10:28:11 INFO - mkdir: C:\slave\test\properties 10:28:11 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url 10:28:11 INFO - Writing to file C:\slave\test\properties\build_url 10:28:11 INFO - Contents: 10:28:11 INFO - build_url:https://queue.taskcluster.net/v1/task/K0tqtoNwRYeAb-YN4Y3fRQ/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 10:28:12 INFO - Running post-action listener: _resource_record_post_action 10:28:12 INFO - Running post-action listener: set_extra_try_arguments 10:28:12 INFO - [mozharness: 2016-10-20 17:28:12.383000Z] Finished download-and-extract step (success) 10:28:12 INFO - [mozharness: 2016-10-20 17:28:12.383000Z] Running populate-webroot step. 10:28:12 INFO - Running pre-action listener: _resource_record_pre_action 10:28:12 INFO - Running main action method: populate_webroot 10:28:12 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 10:28:12 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 10:28:12 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 10:28:12 INFO - u'--tppagecycles', 10:28:12 INFO - u'7'], 10:28:12 INFO - u'tests': [u'tsvgm']}}, 10:28:12 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'], 10:28:12 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 10:28:12 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']}, 10:28:12 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'], 10:28:12 INFO - u'tests': [u'dromaeo_css', u'kraken']}, 10:28:12 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']}, 10:28:12 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:28:12 INFO - u'talos_options': [u'--disable-e10s'], 10:28:12 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 10:28:12 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:28:12 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 10:28:12 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:28:12 INFO - u'talos_options': [u'--disable-e10s'], 10:28:12 INFO - u'tests': [u'damp', u'tps']}, 10:28:12 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:28:12 INFO - u'tests': [u'damp', u'tps']}, 10:28:12 INFO - u'g3': {u'talos_options': [u'--disable-e10s'], 10:28:12 INFO - u'tests': [u'dromaeo_dom']}, 10:28:12 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 10:28:12 INFO - u'g4': {u'talos_options': [u'--disable-e10s'], 10:28:12 INFO - u'tests': [u'basic_compositor_video']}, 10:28:12 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']}, 10:28:12 INFO - u'other': {u'talos_options': [u'--disable-e10s'], 10:28:12 INFO - u'tests': [u'a11yr', 10:28:12 INFO - u'ts_paint', 10:28:12 INFO - u'tpaint', 10:28:12 INFO - u'sessionrestore', 10:28:12 INFO - u'sessionrestore_no_auto_restore', 10:28:12 INFO - u'tabpaint']}, 10:28:12 INFO - u'other-e10s': {u'tests': [u'a11yr', 10:28:12 INFO - u'ts_paint', 10:28:12 INFO - u'tpaint', 10:28:12 INFO - u'sessionrestore', 10:28:12 INFO - u'sessionrestore_no_auto_restore', 10:28:12 INFO - u'tabpaint']}, 10:28:12 INFO - u'other-e10s_l64': {u'tests': [u'a11yr', 10:28:12 INFO - u'ts_paint', 10:28:12 INFO - u'tpaint', 10:28:12 INFO - u'sessionrestore', 10:28:12 INFO - u'sessionrestore_no_auto_restore', 10:28:12 INFO - u'tabpaint']}, 10:28:12 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr', 10:28:12 INFO - u'ts_paint', 10:28:12 INFO - u'tpaint', 10:28:12 INFO - u'sessionrestore', 10:28:12 INFO - u'sessionrestore_no_auto_restore', 10:28:12 INFO - u'tabpaint']}, 10:28:12 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'], 10:28:12 INFO - u'tests': [u'a11yr', 10:28:12 INFO - u'ts_paint', 10:28:12 INFO - u'tpaint', 10:28:12 INFO - u'sessionrestore', 10:28:12 INFO - u'sessionrestore_no_auto_restore', 10:28:12 INFO - u'tabpaint']}, 10:28:12 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'], 10:28:12 INFO - u'tests': [u'a11yr', 10:28:12 INFO - u'ts_paint', 10:28:12 INFO - u'tpaint', 10:28:12 INFO - u'sessionrestore', 10:28:12 INFO - u'sessionrestore_no_auto_restore', 10:28:12 INFO - u'tabpaint']}, 10:28:12 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'], 10:28:12 INFO - u'tests': [u'tsvgx', 10:28:12 INFO - u'tsvgr_opacity', 10:28:12 INFO - u'tart', 10:28:12 INFO - u'tscrollx', 10:28:12 INFO - u'cart']}, 10:28:12 INFO - u'svgr-e10s': {u'tests': [u'tsvgx', 10:28:12 INFO - u'tsvgr_opacity', 10:28:12 INFO - u'tart', 10:28:12 INFO - u'tscrollx', 10:28:12 INFO - u'cart']}, 10:28:12 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:28:12 INFO - u'talos_options': [u'--disable-e10s'], 10:28:12 INFO - u'tests': [u'tp5o']}, 10:28:12 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:28:12 INFO - u'tests': [u'tp5o']}, 10:28:12 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:28:12 INFO - u'talos_options': [u'--disable-e10s', 10:28:12 INFO - u'--xperf_path', 10:28:12 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:28:12 INFO - u'tests': [u'tp5n']}, 10:28:12 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:28:12 INFO - u'talos_options': [u'--xperf_path', 10:28:12 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:28:12 INFO - u'tests': [u'tp5n']}}, 10:28:12 INFO - u'talos.zip': {u'path': u'', 10:28:12 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:28:12 INFO - Running post-action listener: _resource_record_post_action 10:28:12 INFO - [mozharness: 2016-10-20 17:28:12.460000Z] Finished populate-webroot step (success) 10:28:12 INFO - [mozharness: 2016-10-20 17:28:12.460000Z] Running create-virtualenv step. 10:28:12 INFO - Running pre-action listener: _resource_record_pre_action 10:28:12 INFO - Running main action method: create_virtualenv 10:28:12 INFO - Creating virtualenv C:\slave\test\build/venv 10:28:12 INFO - mkdir: C:\slave\test\build\venv\Scripts 10:28:12 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll 10:28:12 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:28:12 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:28:12 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:28:24 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe 10:28:29 INFO - Installing distribute......................................................................................................................................................................................done. 10:28:30 INFO - Return code: 0 10:28:30 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version'] 10:28:30 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version 10:28:31 INFO - Reading from file tmpfile_stdout 10:28:31 INFO - Output received: 10:28:31 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:28:31 INFO - Using _rmtree_windows ... 10:28:31 INFO - Using _rmtree_windows ... 10:28:31 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv 10:28: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:28:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:28: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:28:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:28: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017E1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01885E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0178B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01892E90>, '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 0x0121A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01954520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-107', 'USERDOMAIN': 'T-W732-IX-107', '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-107', '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:28: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', 'pip>=1.5'] in C:\slave\test\build 10:28: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 pip>=1.5 10:28:31 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 10:28:31 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 10:28:31 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 10:28:31 INFO - 'COMPUTERNAME': 'T-W732-IX-107', 10:28:31 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 10:28:31 INFO - 'CYGWINBASE': 'C:\\cygwin', 10:28:31 INFO - 'DCLOCATION': 'SCL3', 10:28:31 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 10:28:31 INFO - 'FP_NO_HOST_CHECK': 'NO', 10:28:31 INFO - 'HOMEDRIVE': 'C:', 10:28:31 INFO - 'HOMEPATH': '\\Users\\cltbld', 10:28:31 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 10:28:31 INFO - 'KTS_VERSION': '1.19c', 10:28:31 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 10:28:31 INFO - 'LOGONSERVER': '\\\\T-W732-IX-107', 10:28:31 INFO - 'MONDIR': 'C:\\Monitor_config\\', 10:28:31 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 10:28:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:28:31 INFO - 'MOZ_NO_REMOTE': '1', 10:28:31 INFO - 'NO_EM_RESTART': '1', 10:28:31 INFO - 'NUMBER_OF_PROCESSORS': '8', 10:28:31 INFO - 'OS': 'Windows_NT', 10:28:31 INFO - 'OURDRIVE': 'C:', 10:28: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:28:31 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 10:28:31 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 10:28:31 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 10:28:31 INFO - 'PROCESSOR_LEVEL': '6', 10:28:31 INFO - 'PROCESSOR_REVISION': '1e05', 10:28:31 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 10:28:31 INFO - 'PROGRAMFILES': 'C:\\Program Files', 10:28:31 INFO - 'PROMPT': '$P$G', 10:28:31 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 10:28:31 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 10:28:31 INFO - 'PUBLIC': 'C:\\Users\\Public', 10:28:31 INFO - 'PWD': 'C:\\slave\\test', 10:28:31 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 10:28:31 INFO - 'SLAVEDIR': 'C:\\slave\\', 10:28:31 INFO - 'SYSTEMDRIVE': 'C:', 10:28:31 INFO - 'SYSTEMROOT': 'C:\\windows', 10:28:31 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:28:31 INFO - 'TEST1': 'testie', 10:28:31 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:28:31 INFO - 'USERDOMAIN': 'T-W732-IX-107', 10:28:31 INFO - 'USERNAME': 'cltbld', 10:28:31 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 10:28:31 INFO - 'WINDIR': 'C:\\windows', 10:28:31 INFO - 'WINDOWS_TRACING_FLAGS': '3', 10:28:31 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 10:28:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 10:28:32 INFO - Ignoring indexes: http://pypi.python.org/simple/ 10:28:32 INFO - Downloading/unpacking pip>=1.5 10:28:36 INFO - Running setup.py egg_info for package pip 10:28:36 INFO - warning: no files found matching 'pip\cacert.pem' 10:28:36 INFO - warning: no files found matching '*.html' under directory 'docs' 10:28:36 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 10:28:36 INFO - no previously-included directories found matching 'docs\_build\_sources' 10:28:36 INFO - Installing collected packages: pip 10:28:36 INFO - Found existing installation: pip 0.8.2 10:28:36 INFO - Uninstalling pip: 10:28:36 INFO - Successfully uninstalled pip 10:28:36 INFO - Running setup.py install for pip 10:28:36 INFO - warning: no files found matching 'pip\cacert.pem' 10:28:36 INFO - warning: no files found matching '*.html' under directory 'docs' 10:28:36 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 10:28:36 INFO - no previously-included directories found matching 'docs\_build\_sources' 10:28:36 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts 10:28:36 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts 10:28:36 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts 10:28:36 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts 10:28:36 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts 10:28:36 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts 10:28:36 INFO - Successfully installed pip 10:28:36 INFO - Cleaning up... 10:28:36 INFO - Return code: 0 10:28:36 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv 10:28: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:28:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:28: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:28:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:28: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', '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 0x017E1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01885E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0178B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01892E90>, '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 0x0121A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01954520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-107', 'USERDOMAIN': 'T-W732-IX-107', '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-107', '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:28: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', 'psutil>=3.1.1'] in C:\slave\test\build 10:28: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 psutil>=3.1.1 10:28:36 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 10:28:36 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 10:28:36 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 10:28:36 INFO - 'COMPUTERNAME': 'T-W732-IX-107', 10:28:36 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 10:28:36 INFO - 'CYGWINBASE': 'C:\\cygwin', 10:28:36 INFO - 'DCLOCATION': 'SCL3', 10:28:36 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 10:28:36 INFO - 'FP_NO_HOST_CHECK': 'NO', 10:28:36 INFO - 'HOMEDRIVE': 'C:', 10:28:36 INFO - 'HOMEPATH': '\\Users\\cltbld', 10:28:36 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 10:28:36 INFO - 'KTS_VERSION': '1.19c', 10:28:36 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 10:28:36 INFO - 'LOGONSERVER': '\\\\T-W732-IX-107', 10:28:36 INFO - 'MONDIR': 'C:\\Monitor_config\\', 10:28:36 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 10:28:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:28:36 INFO - 'MOZ_NO_REMOTE': '1', 10:28:36 INFO - 'NO_EM_RESTART': '1', 10:28:36 INFO - 'NUMBER_OF_PROCESSORS': '8', 10:28:36 INFO - 'OS': 'Windows_NT', 10:28:36 INFO - 'OURDRIVE': 'C:', 10:28: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:28:36 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 10:28:36 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 10:28:36 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 10:28:36 INFO - 'PROCESSOR_LEVEL': '6', 10:28:36 INFO - 'PROCESSOR_REVISION': '1e05', 10:28:36 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 10:28:36 INFO - 'PROGRAMFILES': 'C:\\Program Files', 10:28:36 INFO - 'PROMPT': '$P$G', 10:28:36 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 10:28:36 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 10:28:36 INFO - 'PUBLIC': 'C:\\Users\\Public', 10:28:36 INFO - 'PWD': 'C:\\slave\\test', 10:28:36 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 10:28:36 INFO - 'SLAVEDIR': 'C:\\slave\\', 10:28:36 INFO - 'SYSTEMDRIVE': 'C:', 10:28:36 INFO - 'SYSTEMROOT': 'C:\\windows', 10:28:36 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:28:36 INFO - 'TEST1': 'testie', 10:28:36 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:28:36 INFO - 'USERDOMAIN': 'T-W732-IX-107', 10:28:36 INFO - 'USERNAME': 'cltbld', 10:28:36 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 10:28:36 INFO - 'WINDIR': 'C:\\windows', 10:28:36 INFO - 'WINDOWS_TRACING_FLAGS': '3', 10:28:36 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 10:28:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 10:28:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:28:38 INFO - Downloading/unpacking psutil>=3.1.1 10:28:38 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:28:38 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:28:39 INFO - Installing collected packages: psutil 10:28:40 INFO - Successfully installed psutil 10:28:40 INFO - Cleaning up... 10:28:40 INFO - Return code: 0 10:28:40 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv 10:28:40 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:28:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:28:40 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:28:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:28:40 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 0x017E1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01885E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0178B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01892E90>, '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 0x0121A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01954520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-107', 'USERDOMAIN': 'T-W732-IX-107', '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-107', '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:28:40 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:28:40 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:28:40 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 10:28:40 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 10:28:40 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 10:28:40 INFO - 'COMPUTERNAME': 'T-W732-IX-107', 10:28:40 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 10:28:40 INFO - 'CYGWINBASE': 'C:\\cygwin', 10:28:40 INFO - 'DCLOCATION': 'SCL3', 10:28:40 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 10:28:40 INFO - 'FP_NO_HOST_CHECK': 'NO', 10:28:40 INFO - 'HOMEDRIVE': 'C:', 10:28:40 INFO - 'HOMEPATH': '\\Users\\cltbld', 10:28:40 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 10:28:40 INFO - 'KTS_VERSION': '1.19c', 10:28:40 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 10:28:40 INFO - 'LOGONSERVER': '\\\\T-W732-IX-107', 10:28:40 INFO - 'MONDIR': 'C:\\Monitor_config\\', 10:28:40 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 10:28:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:28:40 INFO - 'MOZ_NO_REMOTE': '1', 10:28:40 INFO - 'NO_EM_RESTART': '1', 10:28:40 INFO - 'NUMBER_OF_PROCESSORS': '8', 10:28:40 INFO - 'OS': 'Windows_NT', 10:28:40 INFO - 'OURDRIVE': 'C:', 10:28:40 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:28:40 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 10:28:40 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 10:28:40 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 10:28:40 INFO - 'PROCESSOR_LEVEL': '6', 10:28:40 INFO - 'PROCESSOR_REVISION': '1e05', 10:28:40 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 10:28:40 INFO - 'PROGRAMFILES': 'C:\\Program Files', 10:28:40 INFO - 'PROMPT': '$P$G', 10:28:40 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 10:28:40 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 10:28:40 INFO - 'PUBLIC': 'C:\\Users\\Public', 10:28:40 INFO - 'PWD': 'C:\\slave\\test', 10:28:40 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 10:28:40 INFO - 'SLAVEDIR': 'C:\\slave\\', 10:28:40 INFO - 'SYSTEMDRIVE': 'C:', 10:28:40 INFO - 'SYSTEMROOT': 'C:\\windows', 10:28:40 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:28:40 INFO - 'TEST1': 'testie', 10:28:40 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:28:40 INFO - 'USERDOMAIN': 'T-W732-IX-107', 10:28:40 INFO - 'USERNAME': 'cltbld', 10:28:40 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 10:28:40 INFO - 'WINDIR': 'C:\\windows', 10:28:40 INFO - 'WINDOWS_TRACING_FLAGS': '3', 10:28:40 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 10:28:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 10:28:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:28:40 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:28: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:28: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:28:41 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:28:41 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 10:28:42 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:28:42 INFO - Installing collected packages: mozsystemmonitor 10:28:42 INFO - Running setup.py install for mozsystemmonitor 10:28:42 INFO - Successfully installed mozsystemmonitor 10:28:42 INFO - Cleaning up... 10:28:42 INFO - Return code: 0 10:28:42 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv 10:28:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:28:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:28:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:28:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:28:42 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 0x017E1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01885E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0178B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01892E90>, '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 0x0121A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01954520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-107', 'USERDOMAIN': 'T-W732-IX-107', '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-107', '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:28:42 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:28:42 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:28:42 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 10:28:42 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 10:28:42 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 10:28:42 INFO - 'COMPUTERNAME': 'T-W732-IX-107', 10:28:42 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 10:28:42 INFO - 'CYGWINBASE': 'C:\\cygwin', 10:28:42 INFO - 'DCLOCATION': 'SCL3', 10:28:42 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 10:28:42 INFO - 'FP_NO_HOST_CHECK': 'NO', 10:28:42 INFO - 'HOMEDRIVE': 'C:', 10:28:42 INFO - 'HOMEPATH': '\\Users\\cltbld', 10:28:42 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 10:28:42 INFO - 'KTS_VERSION': '1.19c', 10:28:42 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 10:28:42 INFO - 'LOGONSERVER': '\\\\T-W732-IX-107', 10:28:42 INFO - 'MONDIR': 'C:\\Monitor_config\\', 10:28:42 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 10:28:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:28:42 INFO - 'MOZ_NO_REMOTE': '1', 10:28:42 INFO - 'NO_EM_RESTART': '1', 10:28:42 INFO - 'NUMBER_OF_PROCESSORS': '8', 10:28:42 INFO - 'OS': 'Windows_NT', 10:28:42 INFO - 'OURDRIVE': 'C:', 10:28:42 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 10:28:42 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 10:28:42 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 10:28:42 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 10:28:42 INFO - 'PROCESSOR_LEVEL': '6', 10:28:42 INFO - 'PROCESSOR_REVISION': '1e05', 10:28:42 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 10:28:42 INFO - 'PROGRAMFILES': 'C:\\Program Files', 10:28:42 INFO - 'PROMPT': '$P$G', 10:28:42 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 10:28:42 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 10:28:42 INFO - 'PUBLIC': 'C:\\Users\\Public', 10:28:42 INFO - 'PWD': 'C:\\slave\\test', 10:28:42 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 10:28:42 INFO - 'SLAVEDIR': 'C:\\slave\\', 10:28:42 INFO - 'SYSTEMDRIVE': 'C:', 10:28:42 INFO - 'SYSTEMROOT': 'C:\\windows', 10:28:42 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:28:42 INFO - 'TEST1': 'testie', 10:28:42 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:28:42 INFO - 'USERDOMAIN': 'T-W732-IX-107', 10:28:42 INFO - 'USERNAME': 'cltbld', 10:28:42 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 10:28:42 INFO - 'WINDIR': 'C:\\windows', 10:28:42 INFO - 'WINDOWS_TRACING_FLAGS': '3', 10:28:42 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 10:28:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 10:28:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:28:42 INFO - Downloading/unpacking jsonschema==2.5.1 10:28:42 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:28:42 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:28:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:28:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:28:43 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:28:43 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:28:44 INFO - Downloading functools32-3.2.3-2.tar.gz 10:28:44 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32 10:28:44 INFO - warning: no files found matching '*.txt' 10:28:44 INFO - no previously-included directories found matching 'build' 10:28:44 INFO - no previously-included directories found matching 'dist' 10:28:44 INFO - no previously-included directories found matching '.git*' 10:28:44 INFO - Installing collected packages: jsonschema, functools32 10:28:44 INFO - Running setup.py install for functools32 10:28:44 INFO - warning: no files found matching '*.txt' 10:28:44 INFO - no previously-included directories found matching 'build' 10:28:44 INFO - no previously-included directories found matching 'dist' 10:28:44 INFO - no previously-included directories found matching '.git*' 10:28:44 INFO - Successfully installed jsonschema functools32 10:28:44 INFO - Cleaning up... 10:28:44 INFO - Return code: 0 10:28:44 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv 10:28:44 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:28:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:28:44 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:28:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:28:44 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 0x017E1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01885E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0178B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01892E90>, '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 0x0121A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01954520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-107', 'USERDOMAIN': 'T-W732-IX-107', '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-107', '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:28:44 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:28:44 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:28:44 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 10:28:44 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 10:28:44 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 10:28:44 INFO - 'COMPUTERNAME': 'T-W732-IX-107', 10:28:44 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 10:28:44 INFO - 'CYGWINBASE': 'C:\\cygwin', 10:28:44 INFO - 'DCLOCATION': 'SCL3', 10:28:44 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 10:28:44 INFO - 'FP_NO_HOST_CHECK': 'NO', 10:28:44 INFO - 'HOMEDRIVE': 'C:', 10:28:44 INFO - 'HOMEPATH': '\\Users\\cltbld', 10:28:44 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 10:28:44 INFO - 'KTS_VERSION': '1.19c', 10:28:44 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 10:28:44 INFO - 'LOGONSERVER': '\\\\T-W732-IX-107', 10:28:44 INFO - 'MONDIR': 'C:\\Monitor_config\\', 10:28:44 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 10:28:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:28:44 INFO - 'MOZ_NO_REMOTE': '1', 10:28:44 INFO - 'NO_EM_RESTART': '1', 10:28:44 INFO - 'NUMBER_OF_PROCESSORS': '8', 10:28:44 INFO - 'OS': 'Windows_NT', 10:28:44 INFO - 'OURDRIVE': 'C:', 10:28:44 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:28:44 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 10:28:44 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 10:28:44 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 10:28:44 INFO - 'PROCESSOR_LEVEL': '6', 10:28:44 INFO - 'PROCESSOR_REVISION': '1e05', 10:28:44 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 10:28:44 INFO - 'PROGRAMFILES': 'C:\\Program Files', 10:28:44 INFO - 'PROMPT': '$P$G', 10:28:44 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 10:28:44 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 10:28:44 INFO - 'PUBLIC': 'C:\\Users\\Public', 10:28:44 INFO - 'PWD': 'C:\\slave\\test', 10:28:44 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 10:28:44 INFO - 'SLAVEDIR': 'C:\\slave\\', 10:28:44 INFO - 'SYSTEMDRIVE': 'C:', 10:28:44 INFO - 'SYSTEMROOT': 'C:\\windows', 10:28:44 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:28:44 INFO - 'TEST1': 'testie', 10:28:44 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:28:44 INFO - 'USERDOMAIN': 'T-W732-IX-107', 10:28:44 INFO - 'USERNAME': 'cltbld', 10:28:44 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 10:28:44 INFO - 'WINDIR': 'C:\\windows', 10:28:44 INFO - 'WINDOWS_TRACING_FLAGS': '3', 10:28:44 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 10:28:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 10:28:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:28:45 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages 10:28:45 INFO - Cleaning up... 10:28:45 INFO - Return code: 0 10:28:45 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv 10:28: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:28:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:28: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:28:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:28:45 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 0x017E1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01885E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0178B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01892E90>, '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 0x0121A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01954520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-107', 'USERDOMAIN': 'T-W732-IX-107', '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-107', '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:28:45 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:28:45 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:28:45 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 10:28:45 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 10:28:45 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 10:28:45 INFO - 'COMPUTERNAME': 'T-W732-IX-107', 10:28:45 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 10:28:45 INFO - 'CYGWINBASE': 'C:\\cygwin', 10:28:45 INFO - 'DCLOCATION': 'SCL3', 10:28:45 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 10:28:45 INFO - 'FP_NO_HOST_CHECK': 'NO', 10:28:45 INFO - 'HOMEDRIVE': 'C:', 10:28:45 INFO - 'HOMEPATH': '\\Users\\cltbld', 10:28:45 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 10:28:45 INFO - 'KTS_VERSION': '1.19c', 10:28:45 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 10:28:45 INFO - 'LOGONSERVER': '\\\\T-W732-IX-107', 10:28:45 INFO - 'MONDIR': 'C:\\Monitor_config\\', 10:28:45 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 10:28:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:28:45 INFO - 'MOZ_NO_REMOTE': '1', 10:28:45 INFO - 'NO_EM_RESTART': '1', 10:28:45 INFO - 'NUMBER_OF_PROCESSORS': '8', 10:28:45 INFO - 'OS': 'Windows_NT', 10:28:45 INFO - 'OURDRIVE': 'C:', 10:28: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:28:45 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 10:28:45 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 10:28:45 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 10:28:45 INFO - 'PROCESSOR_LEVEL': '6', 10:28:45 INFO - 'PROCESSOR_REVISION': '1e05', 10:28:45 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 10:28:45 INFO - 'PROGRAMFILES': 'C:\\Program Files', 10:28:45 INFO - 'PROMPT': '$P$G', 10:28:45 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 10:28:45 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 10:28:45 INFO - 'PUBLIC': 'C:\\Users\\Public', 10:28:45 INFO - 'PWD': 'C:\\slave\\test', 10:28:45 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 10:28:45 INFO - 'SLAVEDIR': 'C:\\slave\\', 10:28:45 INFO - 'SYSTEMDRIVE': 'C:', 10:28:45 INFO - 'SYSTEMROOT': 'C:\\windows', 10:28:45 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:28:45 INFO - 'TEST1': 'testie', 10:28:45 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:28:45 INFO - 'USERDOMAIN': 'T-W732-IX-107', 10:28:45 INFO - 'USERNAME': 'cltbld', 10:28:45 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 10:28:45 INFO - 'WINDIR': 'C:\\windows', 10:28:45 INFO - 'WINDOWS_TRACING_FLAGS': '3', 10:28:45 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 10:28:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 10:28:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:28:45 INFO - Downloading/unpacking blobuploader==1.2.4 10:28:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:28:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:28:49 INFO - Downloading blobuploader-1.2.4.tar.gz 10:28:49 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 10:28:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:28:49 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:28:49 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:28:49 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests 10:28:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:28:50 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:28:50 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:28:50 INFO - Downloading docopt-0.6.1.tar.gz 10:28:50 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt 10:28:50 INFO - Installing collected packages: blobuploader, requests, docopt 10:28:50 INFO - Running setup.py install for blobuploader 10:28:50 INFO - Running setup.py install for requests 10:28:51 INFO - Running setup.py install for docopt 10:28:51 INFO - Successfully installed blobuploader requests docopt 10:28:51 INFO - Cleaning up... 10:28:52 INFO - Return code: 0 10:28:52 INFO - Installing None into virtualenv C:\slave\test\build/venv 10:28:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:28:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:28:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:28:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:28:52 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 0x017E1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01885E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0178B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01892E90>, '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 0x0121A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01954520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-107', 'USERDOMAIN': 'T-W732-IX-107', '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-107', '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:28:52 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:28:52 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:28:52 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 10:28:52 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 10:28:52 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 10:28:52 INFO - 'COMPUTERNAME': 'T-W732-IX-107', 10:28:52 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 10:28:52 INFO - 'CYGWINBASE': 'C:\\cygwin', 10:28:52 INFO - 'DCLOCATION': 'SCL3', 10:28:52 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 10:28:52 INFO - 'FP_NO_HOST_CHECK': 'NO', 10:28:52 INFO - 'HOMEDRIVE': 'C:', 10:28:52 INFO - 'HOMEPATH': '\\Users\\cltbld', 10:28:52 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 10:28:52 INFO - 'KTS_VERSION': '1.19c', 10:28:52 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 10:28:52 INFO - 'LOGONSERVER': '\\\\T-W732-IX-107', 10:28:52 INFO - 'MONDIR': 'C:\\Monitor_config\\', 10:28:52 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 10:28:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:28:52 INFO - 'MOZ_NO_REMOTE': '1', 10:28:52 INFO - 'NO_EM_RESTART': '1', 10:28:52 INFO - 'NUMBER_OF_PROCESSORS': '8', 10:28:52 INFO - 'OS': 'Windows_NT', 10:28:52 INFO - 'OURDRIVE': 'C:', 10:28:52 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:28:52 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 10:28:52 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 10:28:52 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 10:28:52 INFO - 'PROCESSOR_LEVEL': '6', 10:28:52 INFO - 'PROCESSOR_REVISION': '1e05', 10:28:52 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 10:28:52 INFO - 'PROGRAMFILES': 'C:\\Program Files', 10:28:52 INFO - 'PROMPT': '$P$G', 10:28:52 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 10:28:52 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 10:28:52 INFO - 'PUBLIC': 'C:\\Users\\Public', 10:28:52 INFO - 'PWD': 'C:\\slave\\test', 10:28:52 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 10:28:52 INFO - 'SLAVEDIR': 'C:\\slave\\', 10:28:52 INFO - 'SYSTEMDRIVE': 'C:', 10:28:52 INFO - 'SYSTEMROOT': 'C:\\windows', 10:28:52 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:28:52 INFO - 'TEST1': 'testie', 10:28:52 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:28:52 INFO - 'USERDOMAIN': 'T-W732-IX-107', 10:28:52 INFO - 'USERNAME': 'cltbld', 10:28:52 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 10:28:52 INFO - 'WINDIR': 'C:\\windows', 10:28:52 INFO - 'WINDOWS_TRACING_FLAGS': '3', 10:28:52 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 10:28:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 10:28:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:28:53 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 10:28:53 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-isu_wi-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 10:28:53 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 10:28:53 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kapies-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 10:28:53 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 10:28:53 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-4t5cq9-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 10:28:53 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 10:28:53 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qcxcn5-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 10:28:53 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 10:28:53 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-9w6ctt-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 10:28:54 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 10:28:54 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ki4azp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 10:28:54 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 10:28:54 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zwwvdz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 10:28:54 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 10:28:54 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-aeqsaj-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 10:28:54 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 10:28:54 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gwgxy6-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 10:28:54 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 10:28:54 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ugtied-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 10:28:54 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 10:28:54 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-y4hzpl-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 10:28:54 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 10:28:54 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-1yc85u-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 10:28:54 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 10:28:54 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-_dcbxw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 10:28:55 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 10:28:55 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-5andsf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 10:28:55 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 10:28:55 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-yeuhe0-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 10:28:55 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 10:28:55 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vllnvy-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 10:28:55 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 10:28:55 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ukkpxc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 10:28:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:28:55 INFO - Running setup.py install for manifestparser 10:28:55 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts 10:28:55 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts 10:28:56 INFO - Running setup.py install for mozcrash 10:28:56 INFO - Running setup.py install for mozdebug 10:28:56 INFO - Running setup.py install for mozdevice 10:28:57 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts 10:28:57 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts 10:28:57 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts 10:28:57 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts 10:28:57 INFO - Running setup.py install for mozfile 10:28:57 INFO - Running setup.py install for mozhttpd 10:28:58 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts 10:28:58 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts 10:28:58 INFO - Running setup.py install for mozinfo 10:28:58 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts 10:28:58 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts 10:28:58 INFO - Running setup.py install for mozInstall 10:28:58 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts 10:28:58 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts 10:28:58 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts 10:28:58 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts 10:28:58 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts 10:28:58 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts 10:28:58 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts 10:28:58 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts 10:28:58 INFO - Running setup.py install for mozleak 10:28:59 INFO - Running setup.py install for mozlog 10:28:59 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts 10:28:59 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts 10:29:00 INFO - Running setup.py install for moznetwork 10:29:00 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts 10:29:00 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts 10:29:00 INFO - Running setup.py install for mozprocess 10:29:01 INFO - Running setup.py install for mozprofile 10:29:01 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts 10:29:01 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts 10:29:01 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts 10:29:01 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts 10:29:01 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts 10:29:01 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts 10:29:01 INFO - Running setup.py install for mozrunner 10:29:01 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts 10:29:01 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts 10:29:02 INFO - Running setup.py install for mozscreenshot 10:29:02 INFO - Running setup.py install for moztest 10:29:03 INFO - Running setup.py install for mozversion 10:29:03 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts 10:29:03 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts 10:29:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:29:03 INFO - Cleaning up... 10:29:03 INFO - Return code: 0 10:29:03 INFO - Installing None into virtualenv C:\slave\test\build/venv 10:29: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:29:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:29: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:29:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:29:03 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 0x017E1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01885E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0178B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01892E90>, '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 0x0121A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01954520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-107', 'USERDOMAIN': 'T-W732-IX-107', '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-107', '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:29:03 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:29:03 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:29:03 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 10:29:03 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 10:29:03 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 10:29:03 INFO - 'COMPUTERNAME': 'T-W732-IX-107', 10:29:03 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 10:29:03 INFO - 'CYGWINBASE': 'C:\\cygwin', 10:29:03 INFO - 'DCLOCATION': 'SCL3', 10:29:03 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 10:29:03 INFO - 'FP_NO_HOST_CHECK': 'NO', 10:29:03 INFO - 'HOMEDRIVE': 'C:', 10:29:03 INFO - 'HOMEPATH': '\\Users\\cltbld', 10:29:03 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 10:29:03 INFO - 'KTS_VERSION': '1.19c', 10:29:03 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 10:29:03 INFO - 'LOGONSERVER': '\\\\T-W732-IX-107', 10:29:03 INFO - 'MONDIR': 'C:\\Monitor_config\\', 10:29:03 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 10:29:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:03 INFO - 'MOZ_NO_REMOTE': '1', 10:29:03 INFO - 'NO_EM_RESTART': '1', 10:29:03 INFO - 'NUMBER_OF_PROCESSORS': '8', 10:29:03 INFO - 'OS': 'Windows_NT', 10:29:03 INFO - 'OURDRIVE': 'C:', 10:29:03 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 10:29:03 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 10:29:03 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 10:29:03 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 10:29:03 INFO - 'PROCESSOR_LEVEL': '6', 10:29:03 INFO - 'PROCESSOR_REVISION': '1e05', 10:29:03 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 10:29:03 INFO - 'PROGRAMFILES': 'C:\\Program Files', 10:29:03 INFO - 'PROMPT': '$P$G', 10:29:03 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 10:29:03 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 10:29:03 INFO - 'PUBLIC': 'C:\\Users\\Public', 10:29:03 INFO - 'PWD': 'C:\\slave\\test', 10:29:03 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 10:29:03 INFO - 'SLAVEDIR': 'C:\\slave\\', 10:29:03 INFO - 'SYSTEMDRIVE': 'C:', 10:29:03 INFO - 'SYSTEMROOT': 'C:\\windows', 10:29:03 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:29:03 INFO - 'TEST1': 'testie', 10:29:03 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:29:03 INFO - 'USERDOMAIN': 'T-W732-IX-107', 10:29:03 INFO - 'USERNAME': 'cltbld', 10:29:03 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 10:29:03 INFO - 'WINDIR': 'C:\\windows', 10:29:03 INFO - 'WINDOWS_TRACING_FLAGS': '3', 10:29:03 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 10:29:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 10:29:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:29:03 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 10:29:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fole5q-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 10:29:04 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:29:04 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 10:29:04 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-e7olxq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 10:29:04 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:29:04 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 10:29:04 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dlc6tc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 10:29:04 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:29:04 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 10:29:04 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-12ingj-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 10:29:04 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:29:04 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 10:29:04 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jo7trw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 10:29:04 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:29:04 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 10:29:04 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zscpns-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 10:29:04 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:29:04 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 10:29:04 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-r8hwfq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 10:29:04 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:29:04 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 10:29:04 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-b6sjlq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 10:29:05 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:29:05 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 10:29:05 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ewnpbw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 10:29:05 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:29:05 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 10:29:05 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-knqsu4-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 10:29:05 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:29:05 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 10:29:05 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rvk8fh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 10:29:05 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:29:05 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 10:29:05 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fuyj6t-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 10:29:05 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:29:05 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 10:29:05 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-2f6kn9-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 10:29:05 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:29:05 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 10:29:05 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vkbqnc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 10:29:05 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:29:05 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 10:29:05 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-sb9u4s-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 10:29:05 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:29:05 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 10:29:06 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-3ec6tw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 10:29:06 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:29:06 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 10:29:06 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ujwkqq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 10:29:06 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:29:06 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:29:06 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:29:06 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:29:06 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:29:06 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:29:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10)) 10:29:06 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:29:06 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:29:07 INFO - Downloading blessings-1.6.tar.gz 10:29:07 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings 10:29:07 INFO - Installing collected packages: blessings 10:29:07 INFO - Running setup.py install for blessings 10:29:07 INFO - Successfully installed blessings 10:29:07 INFO - Cleaning up... 10:29:10 INFO - Return code: 0 10:29:10 INFO - Done creating virtualenv C:\slave\test\build/venv. 10:29:10 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 10:29:10 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 10:29:11 INFO - Reading from file tmpfile_stdout 10:29:11 INFO - Using _rmtree_windows ... 10:29:11 INFO - Using _rmtree_windows ... 10:29:11 INFO - Current package versions: 10:29:11 INFO - blessings == 1.6 10:29:11 INFO - blobuploader == 1.2.4 10:29:11 INFO - docopt == 0.6.1 10:29:11 INFO - functools32 == 3.2.3-2 10:29:11 INFO - jsonschema == 2.5.1 10:29:11 INFO - manifestparser == 1.1 10:29:11 INFO - mozInstall == 1.12 10:29:11 INFO - mozcrash == 0.17 10:29:11 INFO - mozdebug == 0.1 10:29:11 INFO - mozdevice == 0.48 10:29:11 INFO - mozfile == 1.2 10:29:11 INFO - mozhttpd == 0.7 10:29:11 INFO - mozinfo == 0.9 10:29:11 INFO - mozleak == 0.1 10:29:11 INFO - mozlog == 3.4 10:29:11 INFO - moznetwork == 0.27 10:29:11 INFO - mozprocess == 0.23 10:29:11 INFO - mozprofile == 0.28 10:29:11 INFO - mozrunner == 6.12 10:29:11 INFO - mozscreenshot == 0.1 10:29:11 INFO - mozsystemmonitor == 0.3 10:29:11 INFO - moztest == 0.7 10:29:11 INFO - mozversion == 1.4 10:29:11 INFO - psutil == 3.1.1 10:29:11 INFO - requests == 1.2.3 10:29:11 INFO - Installing None into virtualenv C:\slave\test\build/venv 10:29:11 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:29:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:29:11 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:29:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:29:11 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 0x017E1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01885E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0178B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01892E90>, '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 0x0121A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01954520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-107', 'USERDOMAIN': 'T-W732-IX-107', '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-107', '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:29:11 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:29:11 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:29:11 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 10:29:11 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 10:29:11 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 10:29:11 INFO - 'COMPUTERNAME': 'T-W732-IX-107', 10:29:11 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 10:29:11 INFO - 'CYGWINBASE': 'C:\\cygwin', 10:29:11 INFO - 'DCLOCATION': 'SCL3', 10:29:11 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 10:29:11 INFO - 'FP_NO_HOST_CHECK': 'NO', 10:29:11 INFO - 'HOMEDRIVE': 'C:', 10:29:11 INFO - 'HOMEPATH': '\\Users\\cltbld', 10:29:11 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 10:29:11 INFO - 'KTS_VERSION': '1.19c', 10:29:11 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 10:29:11 INFO - 'LOGONSERVER': '\\\\T-W732-IX-107', 10:29:11 INFO - 'MONDIR': 'C:\\Monitor_config\\', 10:29:11 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 10:29:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:11 INFO - 'MOZ_NO_REMOTE': '1', 10:29:11 INFO - 'NO_EM_RESTART': '1', 10:29:11 INFO - 'NUMBER_OF_PROCESSORS': '8', 10:29:11 INFO - 'OS': 'Windows_NT', 10:29:11 INFO - 'OURDRIVE': 'C:', 10:29:11 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:29:11 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 10:29:11 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 10:29:11 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 10:29:11 INFO - 'PROCESSOR_LEVEL': '6', 10:29:11 INFO - 'PROCESSOR_REVISION': '1e05', 10:29:11 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 10:29:11 INFO - 'PROGRAMFILES': 'C:\\Program Files', 10:29:11 INFO - 'PROMPT': '$P$G', 10:29:11 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 10:29:11 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 10:29:11 INFO - 'PUBLIC': 'C:\\Users\\Public', 10:29:11 INFO - 'PWD': 'C:\\slave\\test', 10:29:11 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 10:29:11 INFO - 'SLAVEDIR': 'C:\\slave\\', 10:29:11 INFO - 'SYSTEMDRIVE': 'C:', 10:29:11 INFO - 'SYSTEMROOT': 'C:\\windows', 10:29:11 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:29:11 INFO - 'TEST1': 'testie', 10:29:11 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:29:11 INFO - 'USERDOMAIN': 'T-W732-IX-107', 10:29:11 INFO - 'USERNAME': 'cltbld', 10:29:11 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 10:29:11 INFO - 'WINDIR': 'C:\\windows', 10:29:11 INFO - 'WINDOWS_TRACING_FLAGS': '3', 10:29:11 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 10:29:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 10:29:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:29:11 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:29:11 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:29:11 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:29:11 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:29:11 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:29:11 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:29:11 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:29:11 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:29:11 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:29:11 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:29:11 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:29:11 INFO - Cleaning up... 10:29:11 INFO - Return code: 0 10:29:11 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv 10:29:11 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:29:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:29:11 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:29:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:29:11 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 0x017E1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01885E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0178B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01892E90>, '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 0x0121A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01954520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-107', 'USERDOMAIN': 'T-W732-IX-107', '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-107', '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:29:11 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:29:11 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:29:11 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 10:29:11 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 10:29:11 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 10:29:11 INFO - 'COMPUTERNAME': 'T-W732-IX-107', 10:29:11 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 10:29:11 INFO - 'CYGWINBASE': 'C:\\cygwin', 10:29:11 INFO - 'DCLOCATION': 'SCL3', 10:29:11 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 10:29:11 INFO - 'FP_NO_HOST_CHECK': 'NO', 10:29:11 INFO - 'HOMEDRIVE': 'C:', 10:29:11 INFO - 'HOMEPATH': '\\Users\\cltbld', 10:29:11 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 10:29:11 INFO - 'KTS_VERSION': '1.19c', 10:29:11 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 10:29:11 INFO - 'LOGONSERVER': '\\\\T-W732-IX-107', 10:29:11 INFO - 'MONDIR': 'C:\\Monitor_config\\', 10:29:11 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 10:29:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:11 INFO - 'MOZ_NO_REMOTE': '1', 10:29:11 INFO - 'NO_EM_RESTART': '1', 10:29:11 INFO - 'NUMBER_OF_PROCESSORS': '8', 10:29:11 INFO - 'OS': 'Windows_NT', 10:29:11 INFO - 'OURDRIVE': 'C:', 10:29:11 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:29:11 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 10:29:11 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 10:29:11 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 10:29:11 INFO - 'PROCESSOR_LEVEL': '6', 10:29:11 INFO - 'PROCESSOR_REVISION': '1e05', 10:29:11 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 10:29:11 INFO - 'PROGRAMFILES': 'C:\\Program Files', 10:29:11 INFO - 'PROMPT': '$P$G', 10:29:11 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 10:29:11 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 10:29:11 INFO - 'PUBLIC': 'C:\\Users\\Public', 10:29:11 INFO - 'PWD': 'C:\\slave\\test', 10:29:11 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 10:29:11 INFO - 'SLAVEDIR': 'C:\\slave\\', 10:29:11 INFO - 'SYSTEMDRIVE': 'C:', 10:29:11 INFO - 'SYSTEMROOT': 'C:\\windows', 10:29:11 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:29:11 INFO - 'TEST1': 'testie', 10:29:11 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:29:11 INFO - 'USERDOMAIN': 'T-W732-IX-107', 10:29:11 INFO - 'USERNAME': 'cltbld', 10:29:11 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 10:29:11 INFO - 'WINDIR': 'C:\\windows', 10:29:11 INFO - 'WINDOWS_TRACING_FLAGS': '3', 10:29:11 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 10:29:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 10:29:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:29:11 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages 10:29:11 INFO - Cleaning up... 10:29:11 INFO - Return code: 0 10:29:11 INFO - Running post-action listener: _resource_record_post_action 10:29:11 INFO - Running post-action listener: _start_resource_monitoring 10:29:11 INFO - Starting resource monitoring. 10:29:11 INFO - [mozharness: 2016-10-20 17:29:11.928000Z] Finished create-virtualenv step (success) 10:29:11 INFO - [mozharness: 2016-10-20 17:29:11.928000Z] Running install step. 10:29:11 INFO - Running pre-action listener: _resource_record_pre_action 10:29:11 INFO - Running main action method: install 10:29:11 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 10:29:11 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 10:29:12 INFO - Reading from file tmpfile_stdout 10:29:12 INFO - Using _rmtree_windows ... 10:29:12 INFO - Using _rmtree_windows ... 10:29:12 INFO - Detecting whether we're running mozinstall >=1.0... 10:29:12 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h'] 10:29:12 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h 10:29:12 INFO - Reading from file tmpfile_stdout 10:29:12 INFO - Output received: 10:29:12 INFO - Usage: mozinstall-script.py [options] installer 10:29:12 INFO - Options: 10:29:12 INFO - -h, --help show this help message and exit 10:29:12 INFO - -d DEST, --destination=DEST 10:29:12 INFO - Directory to install application into. [default: 10:29:12 INFO - "C:\slave\test"] 10:29:12 INFO - --app=APP Application being installed. [default: firefox] 10:29:12 INFO - Using _rmtree_windows ... 10:29:12 INFO - Using _rmtree_windows ... 10:29:12 INFO - mkdir: C:\slave\test\build\application 10:29:12 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:29:12 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:29:14 INFO - Reading from file tmpfile_stdout 10:29:14 INFO - Output received: 10:29:14 INFO - C:\slave\test\build\application\firefox\firefox.exe 10:29:14 INFO - Using _rmtree_windows ... 10:29:14 INFO - Using _rmtree_windows ... 10:29:14 INFO - Running post-action listener: _resource_record_post_action 10:29:14 INFO - [mozharness: 2016-10-20 17:29:14.476000Z] Finished install step (success) 10:29:14 INFO - [mozharness: 2016-10-20 17:29:14.476000Z] Running run-tests step. 10:29:14 INFO - Running pre-action listener: _resource_record_pre_action 10:29:14 INFO - Running main action method: run_tests 10:29:14 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version'] 10:29:14 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version 10:29:14 INFO - Python 2.7.3 10:29:14 INFO - Return code: 0 10:29:14 INFO - grabbing minidump binary from tooltool 10:29:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:29:14 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 0x01892E90>, '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 0x0121A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01954520>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1 10:29:14 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:29:14 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:29:14 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:29:14 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 10:29:16 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmpfca38n 10:29:16 INFO - INFO - File integrity verified, renaming tmpfca38n to win32-minidump_stackwalk.exe 10:29:16 INFO - Return code: 0 10:29:16 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755 10:29:16 INFO - mkdir: C:\slave\test\build\blobber_upload_dir 10:29:16 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir 10:29:16 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe 10:29:16 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir 10:29:16 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/K0tqtoNwRYeAb-YN4Y3fRQ/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-107', '--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:29:16 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/K0tqtoNwRYeAb-YN4Y3fRQ/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-107 --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:29:16 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 10:29:16 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 10:29:16 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 10:29:16 INFO - 'COMPUTERNAME': 'T-W732-IX-107', 10:29:16 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 10:29:16 INFO - 'CYGWINBASE': 'C:\\cygwin', 10:29:16 INFO - 'DCLOCATION': 'SCL3', 10:29:16 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 10:29:16 INFO - 'FP_NO_HOST_CHECK': 'NO', 10:29:16 INFO - 'HOMEDRIVE': 'C:', 10:29:16 INFO - 'HOMEPATH': '\\Users\\cltbld', 10:29:16 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 10:29:16 INFO - 'KTS_VERSION': '1.19c', 10:29:16 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 10:29:16 INFO - 'LOGONSERVER': '\\\\T-W732-IX-107', 10:29:16 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir', 10:29:16 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe', 10:29:16 INFO - 'MONDIR': 'C:\\Monitor_config\\', 10:29:16 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 10:29:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:16 INFO - 'MOZ_NO_REMOTE': '1', 10:29:16 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir', 10:29:16 INFO - 'NO_EM_RESTART': '1', 10:29:16 INFO - 'NUMBER_OF_PROCESSORS': '8', 10:29:16 INFO - 'OS': 'Windows_NT', 10:29:16 INFO - 'OURDRIVE': 'C:', 10:29:16 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:29:16 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 10:29:16 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 10:29:16 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 10:29:16 INFO - 'PROCESSOR_LEVEL': '6', 10:29:16 INFO - 'PROCESSOR_REVISION': '1e05', 10:29:16 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 10:29:16 INFO - 'PROGRAMFILES': 'C:\\Program Files', 10:29:16 INFO - 'PROMPT': '$P$G', 10:29:16 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 10:29:16 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 10:29:16 INFO - 'PUBLIC': 'C:\\Users\\Public', 10:29:16 INFO - 'PWD': 'C:\\slave\\test', 10:29:16 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos', 10:29:16 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 10:29:16 INFO - 'SLAVEDIR': 'C:\\slave\\', 10:29:16 INFO - 'SYSTEMDRIVE': 'C:', 10:29:16 INFO - 'SYSTEMROOT': 'C:\\windows', 10:29:16 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:29:16 INFO - 'TEST1': 'testie', 10:29:16 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:29:16 INFO - 'USERDOMAIN': 'T-W732-IX-107', 10:29:16 INFO - 'USERNAME': 'cltbld', 10:29:16 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 10:29:16 INFO - 'WINDIR': 'C:\\windows', 10:29:16 INFO - 'WINDOWS_TRACING_FLAGS': '3', 10:29:16 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 10:29:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 10:29:16 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/K0tqtoNwRYeAb-YN4Y3fRQ/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-107', '--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:29:17 INFO - mozversion application_buildid: 20161020073000 10:29:17 INFO - mozversion application_changeset: b04e95606673fff324ef0ddb09de846f7b7eaf82 10:29:17 INFO - mozversion application_display_name: Nightly 10:29:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:29:17 INFO - mozversion application_name: Firefox 10:29:17 INFO - mozversion application_remotingname: firefox 10:29:17 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 10:29:17 INFO - mozversion application_vendor: Mozilla 10:29:17 INFO - mozversion application_version: 52.0a1 10:29:17 INFO - mozversion platform_buildid: 20161020073000 10:29:17 INFO - mozversion platform_changeset: b04e95606673fff324ef0ddb09de846f7b7eaf82 10:29:17 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 10:29:17 INFO - mozversion platform_version: 52.0a1 10:29:17 INFO - using testdate: 1476984557 10:29:17 INFO - actual date: 1476984557 10:29:17 INFO - starting webserver on 'localhost:49695' 10:29:17 INFO - SUITE-START | Running 2 tests 10:29:17 INFO - TEST-START | dromaeo_css 10:29:17 INFO - operating with platform_type : w7_ 10:29:17 INFO - Initialising browser for dromaeo_css test... 10:29:17 INFO - TEST-INFO | started process 3508 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpn3mh8m\profile http://localhost:49695/getInfo.html) 10:29:25 INFO - PROCESS | 3508 | __metrics Screen width/height:1600/1200 10:29:25 INFO - PROCESS | 3508 | colorDepth:24 10:29:25 INFO - PROCESS | 3508 | Browser inner width/height: 1010/674 10:29:25 INFO - PROCESS | 3508 | __metrics 10:29:26 INFO - PROCESS | 3508 | 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:29:26 INFO - PROCESS | 3508 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:29:27 INFO - TEST-INFO | 3508: exit 0 10:29:27 INFO - Browser initialized. 10:29:27 INFO - Running cycle 1/1 for dromaeo_css test... 10:29:27 INFO - TEST-INFO | started process 776 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpn3mh8m\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:29:27 INFO - PROCESS | 776 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0 10:29:38 INFO - PROCESS | 776 | RSS: Main: 122781696 10:29:38 INFO - PROCESS | 776 | 10:30:30 INFO - PROCESS | 776 | 1476984630348 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:32:41 INFO - PROCESS | 776 | Cycle 1(1): loaded http://localhost:49695/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49695/tests/dromaeo/cssquery-ext.html) 10:32:42 INFO - PROCESS | 776 | RSS: Main: 206680064 10:32:42 INFO - PROCESS | 776 | 10:35:44 INFO - PROCESS | 776 | Cycle 1(1): loaded http://localhost:49695/tests/dromaeo/cssquery-ext.html (next: http://localhost:49695/tests/dromaeo/cssquery-jquery.html) 10:35:45 INFO - PROCESS | 776 | RSS: Main: 198639616 10:35:45 INFO - PROCESS | 776 | 10:38:47 INFO - PROCESS | 776 | Cycle 1(1): loaded http://localhost:49695/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49695/tests/dromaeo/cssquery-mootools.html) 10:38:48 INFO - PROCESS | 776 | RSS: Main: 153190400 10:38:48 INFO - PROCESS | 776 | 10:41:50 INFO - PROCESS | 776 | Cycle 1(1): loaded http://localhost:49695/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49695/tests/dromaeo/cssquery-prototype.html) 10:41:50 INFO - PROCESS | 776 | RSS: Main: 138633216 10:41:50 INFO - PROCESS | 776 | 10:44:53 INFO - PROCESS | 776 | Cycle 1(1): loaded http://localhost:49695/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49695/tests/dromaeo/cssquery-yui.html) 10:44:54 INFO - PROCESS | 776 | RSS: Main: 132546560 10:44:54 INFO - PROCESS | 776 | 10:47:56 INFO - PROCESS | 776 | Cycle 1(1): loaded http://localhost:49695/tests/dromaeo/cssquery-yui.html (next: http://localhost:49695/tests/dromaeo/cssquery-dojo.html) 10:47:56 INFO - PROCESS | 776 | RSS: Main: 181190656 10:47:56 INFO - PROCESS | 776 | 10:47:56 INFO - PROCESS | 776 | __start_tp_report 10:47:56 INFO - PROCESS | 776 | _x_x_mozilla_page_load 10:47:56 INFO - PROCESS | 776 | _x_x_mozilla_page_load_details 10:47:56 INFO - PROCESS | 776 | |i|pagename|runs| 10:47:56 INFO - PROCESS | 776 | |0;dojo.html;2733.9043133490327;2755.862206889655;2758.820676656017;2767.8062535622503;2775.7640600548953;4647.790849411776;4717.740524271164;4798.83204087857;4815.422149342084;4824.951750482503;4653.06938612278;4678.742669153204;4690.202665546857;4696.785138353955;4787.44944331402;4087.734297270683;4157.50109986802;4158.168366326731;4167.41656168136;4192.538820729733;4051.635352818246;4130.97934510327;4173.791310434482;4180.163967206571;4223.535411104776;4120.835166593348;4126.793660316972;4127.711060225771;4205.85279515217;4225.091605304859;3951.38753493209;4005.619466150711;4085.55058943516;4154.626083652486;4177;3977.2981630612594;4092.0792821615196;4103.712740108193;4141.171765646883;4149.668026557868;4531.660125490568;4561.361409402686;4621.098885717286;4642.419697537808;4664;4586.770661466913;4778.928316075262;4806.49531799163;4815.783289751969;4884.754387631131;4874.2444921037295;4892.68197567157;4904.558589726942;4910.21436570147;4958.677685950438;4444.911101777946;4477.328400739882;4564.406627138484;4584.129015487047;4584.587387135174;4740.810367585258;4893.853184404474;4938.283948827472;5009.649324547319;5067.949320506747;4722.244440889442;4748.477667456577;4756.191447453941;4756.476787553366;4792.185328494164;4907.950920490821;4910.968696573688;4917.631177661689;4924.975375123173;4935.8025678972435;4843.95156048435;4851.611871050308;4854.708717476963;4859.489753575895;4872.244802055687;4869.659123861366;4902.460729319772;4915.066137433876;4915.705057696549;4917.754112294442;3240.1737556922835;3249.805011699258;3347.8326083696206;3349.062262566485;3349.7990120592353;3003.3993201359817;3062.1119875236377;3068.923276918095;3073.5082386818003;3075.0467355119986;4560.612347950461;4620.2607582786595;4638.907221855611;4671.719696818202;4674.742889141062;4853.053654537402;4920.4833492483485;4938.555530002317;5029.396472423333;5047.268146118792;4534.88662783433;4584.518625544337;4613.192691279013;4708.411448568929;4719.83480578185;4111.259973204852;4160.958390416117;4210.221109094828;4211.115665710236;4245.575442455792;5100.795968161232;5115.641905066684;5163.690178589221;5254.369475662945;5254.947450525446;4903.509649035068;4934.802607895644;5082.847514574569;5105.412877518985;5105.693658380509;4918.188498897642;5054.7978080876355;5061.822836200715;5066.847994560169;5132.10188217053;4821.987382649683;4900.411950565955;4915.483874193301;4934.802607895787;4961.5286547777905;4903.793836870074;4934.457209707001;5020.221865610836;5037.143685573535;5051;4745.976270118766;4789.042191561632;4797.64017698681;4810.494898035656;4837.782299796588;4821.373221481185;4832.782524786323;4860.343853579721;4906.803727850846;4928.408590968963;4674.252119660838;4723.078999595184;4814.855554333376;4820.662553621353;4825.445073816556;4800.5199480051715;4833.661643685049;4840.346553215411;4894.632902532253;4954.801807927643;4808.9901120765035;4818.95181048185;4845.176320025534;4891.095147397743;4915.778789954389 10:47:56 INFO - PROCESS | 776 | |1;ext.html;499.5728652002537;521.6635270250688;522.5427750718121;542.734060310448;543.098456562107;28916.710832891666;29644.555331670017;29814.55278170829;29872.103836884875;29880.551791723115;29503;30013.84993075037;38942.415863762064;39516.407253891215;39980;7799.532028078305;7915.445918785687;7926.9207307927045;7939.523628582275;7947.205279472036;7681.116671582784;7763.805904852367;7764.805879852993;7848.4113691473085;7871.055473343207;6790.185177778673;6843.418309443703;6852.486063545229;6993.47548933832;7976.561289129096;6880.793576192722;9523.523823808782;9715.36850104741;9719.222462203006;10014.89985100147;9620.278479114095;9658.661946831897;9701.90298097024;9702.029797020312;9706.174975127122;36602.63397366019;37384;37671.24657506835;37889;37898.81050594757;2725.7375979437434;2725.8142707922116;2774.2370848016753;3014.6834582368756;3391.7795343302855;28351.858240708665;29346.70653293461;29804.10587682373;29959.550606740915;30387.848060759556;3229.4832826747843;3259.445894197992;3264.3308121834966;3267.803931764102;3350.8827191048194;314897;315925;316779;317444;317962.9999999977;49818.292701215185;50016.49983500118;50319;50348.244776328385;50410.24384634233;28646.85676571604;29337.266568335963;51488;51550.48449515532;51875.22187167196;12708.428120734588;13091.607251782461;13116.868831311565;13127.540536081191;13150.934245328903;8801.559922004002;9618.182454491307;9646.469444180497;9678.75803104928;9708.368956017835;16101.919490402708;16106.75839862403;16710.08094554811;16744.57203705456;16776.412825551048;5422.213779002022;6410.45511131559;6510.674466276667;6556.27880933097;6662.467002639777;701.225146213437;737.3953358246187;782.5891407011364;825.628467189755;878.7846977490428;25991.74008259931;27121.593176102375;27296;27416.86291568569;27596;808.3371635259031;849.5667209723085;860.8493513635088;878.2710350409145;879.6041781198359;13220.867791321964;13690.38393272305;13712.862871371359;13915.234662093682;14099.577012689635;446267;450619;451394.5756780403;459405;461543;46339.76830115828;46382.53617463782;46410.303845442344;46673.29990050152;46697.766511167225;922.9616681233612;968.2641192693603;1062.4103622489592;1068;1071.4053700196466;26704.06535771239;27428.862855685595;27576.310592235353;28096;28177.718222817508;40722;40944.59055409408;40956.79521602373;41548.79225603853;41711.58288417077;385622;386724;387855;436766;587689;36980;37423.43864841975;37454.81272593619;37512.437313439776;37718.8114059428;41153;42177.04760666724;42483.57516424796;42748.78625606852;42959;35556.82221588876;35672.821635891654;35774.4633830498;35793.28413431769;36199;35900.64099358973;35967.99840985846;36172.4574131383;37175.44236836504;37698.62301376951;33519.32961340808;33740.66259337375;34127.14682132967;34356;34439.65560344364;31236;31714;32155.839220803748;32437.376301952765;32817.67182328146;24557.263282101565;24766;25025.74974250307;25074.3731406709;25272 10:47:56 INFO - PROCESS | 776 | |2;jquery.html;274.4401421100954;294.32012052159513;297.4155783884667;307.3422875047398;307.9045495896272;12817;17290;17706.911465442656;17988.19053142607;19189.616207675837;16470.176491175454;16704.916475417605;16834.747478787798;16888.324467021306;17103.657926841457;5082.212257100146;5287.788488460457;5290.576753859682;5299.470052994709;5301.257823904656;4978.5270399312;5247.763850626731;5256.185291279858;5256.316678831766;5273.868153296179;5009.423916249623;5193.298904647881;5207.323048003755;5209.270702101709;5212.139996900507;4931.186354251544;4982.451930287665;5067.113255180348;5178.585713142939;5195.818146364859;4472.798724057393;4484.372187893698;4491.169133710241;4500.639948804088;4500.842470513517;22259.666105008437;22287.108515659354;22428.77571224283;22442.55114897693;22501.21245756407;1686.2327640923472;1751.6496700659918;1887.2639670528504;2419.552382809186;2496.3634273260386;13177.407016684272;13491.595252142451;14059.859401405958;14264.928675356558;14430.134191948517;1650.315119225532;1682.915854207297;1705.7441383792407;1723.1814887928258;1769.1066011664027;82038.58980705058;82228.17771822325;82898;82964;83136.58431707864;8493.660253589911;8629.525376104375;8634.740957771277;8707.695230667023;8800.207981281716;10025.799484010278;10476.68569942903;10495.527701253463;10714.892851071545;10751.462426878781;6383;6606.141201643852;6617.900731489031;6727.226368967534;6768.69540870662;6724.562903411263;6767.898481522781;6789.490788190906;6909;6910.792676219721;5409.891802163935;5546.639468434539;5652.152177173457;5658.519025882763;5659.830205093854;1448.1455940994863;1485.4875068101487;1497.9176145312113;1515.121229686779;1518.840521745223;743.605888878895;806.4637016384039;844.6030365728097;876.4741155306765;891.80826122384;18436.723449148274;19348.70976935347;19493.61012779765;19499;19822.306219282545;814.9877751833727;840.7225615546855;846.5217152308926;868.405142477405;881.2597418168641;10083.697489075339;10308.175345972455;10597.89402105969;10683.89316106829;10723;378159;379766;382335;456030;462001;91544;96076.51961740147;103244;105175;105251.47374263233;352.27255716944427;360.0907708037175;365.1546669460191;366.61871653480614;375.57372382346233;8699.56502174876;8929.151730585583;8945.46327220369;9347.3456858022;9507.524623768923;37663.246735065695;40883;42793;44599.77700111608;47401.76299118482;50956.74521627368;53294.33528323646;63732;64294.678526607066;65258.67370663116;6906.309369063053;6914.997325387892;6988.510804243654;7012.824679382852;7170.211276759448;9016.549172541216;12098.939505302416;12237.143399961917;12292.569760058355;12655.810162847749;10190.898091018995;10238.641647542301;10290.02244786744;10295.17638588896;10486.947565262124;9608.711738647851;10293.816211135816;10388.11701005423;10433.739156521147;10549.103326217357;10488.580456781643;10503.264771465923;10546.05085542305;10637.946810266209;10706.411147386656;7061.894071589033;7082.18554866197;7083.82290442743;7230;7237.3486386225495;9658.71023869285;10210.540525676512;10280.22898282617;10329.586816527555;10478.266521343738 10:47:56 INFO - PROCESS | 776 | |3;mootools.html;1150.9597164099255;1151.4127794824637;1237.1772771107214;1440.7334643091028;1472.7717203833397;6620.6358650274215;7064.505484616086;7076.221615622278;7106.076210092687;7110.182329032163;6233.40782625651;6443.387878151568;6487.091807146991;6701.865962680744;6856.485763567727;1889.1593241007722;1919.9040047997614;1924.1822225554083;1925.9807401926012;1926.4702206893146;1909.0263965377687;1912.80871912809;1918.7601549806275;1937.1379736017477;1941.883486990778;1659.269921234659;1669.8163202047765;1855.1002763659615;1890.5935223926854;1900.5628705397767;1776.6269083492482;1892.7350170976006;1901.5531350132708;1909.255390397746;1916.9616607667908;1798.0919635584078;1801.0544464156292;1813.836754692084;1827.552249698819;1854.6847036003776;6780.118584583973;6788.660566971632;6858.417034552069;6872.518923675345;6883.449324054063;4053.148838743867;4141.937870931938;4149.502059752819;4157.3140431828715;4170.374443833419;2602.944314060162;2658.9468210635873;2662.9067982620513;2688.717684643104;2696.4067905060856;838.061371264182;863.0937515608585;869.3783944479665;870.5299138465222;873.6549063120095;8906.55467226636;8947.686830960885;8978.19196272332;9051.366404351633;9052.909470905273;3675.12568590021;3697.279030589063;3752.699784017272;3773.886703422497;3798.639129282714;7417.888731669029;7526.924730752622;7544.735934242275;7589.316961473494;7716.5370077795515;4155.563665815106;4157.210130075337;4178.331466965271;4185.769782661982;4186.958130418718;3889.591592882763;3897.0647044709067;3907.238088572701;3907.5701672815967;3908.5700572936953;2979.642442906865;3009.4432529982023;3031.242189452637;3080.537919312076;3114;969.3941286695815;984.8965858584888;986.3687240166299;990.7176454710373;993.6124911284605;747.5215861848421;766.5783818899583;790.5572879187673;800.9118996910336;815.983680326402;4413.883888725405;4450.109978004412;4453.688241823106;4614.353990441341;4711.528847115261;326.9553775686706;331.17372156469764;334.2045930685003;347.79653902466833;350.4935368392699;2914.096630044693;2927.809692369989;2996.2509372656837;3104.2239440139965;3271.1495011297234;7973.242541958504;8000.239977202156;8133.511989280662;8272.958635206785;8383.538905360263;8211.7947051323;8413.579321034047;8423.904892364077;8433.789155271168;8434.28308593764;6411.743530258831;6512.446442052384;6531;6532.444742196966;6567.737290508422;4583;4669.41632295963;4758.785854636619;4887.926681099858;4920.360353154067;7699;7801.648925798466;7884.369250460064;7928.7621371358955;7954.5624990623755;7742.729004484816;7922.0889597697005;8051.8792218115605;8060.395470339865;8066.717664881701;5868.647881127146;6183;6209.316975132822;6217.129601855834;6229.1590635265475;6663.633500157538;6758.42553382968;6787.932120678927;6798.830029249309;6805.523613347216;5231.895362092813;5399.70301633414;6170.629762214281;6236.656983865931;6239.313675495782;5933.258342707161;6043.667598282137;6144.53915956314;6197.318294987457;6199.1011303361665;5388.218708287198;5732.226149469769;5752.223449834219;5755.5683323750045;5758.71206439668;4070.063885306337;4084.244414783275;4126.298529249974;4132.876013719593;4287.95712042888;3853.595372485961;4048.473698419187;4092.7135100542678;4095.7542547447247;4166.33338665812 10:47:56 INFO - PROCESS | 776 | |4;prototype.html;238.78032210366462;241.1667688137483;241.60738799450885;245.70269973332267;247.8822559284339;5017.37282839645;5109.642325037249;5113.437521872591;5124.6925184488955;5129.871753206172;4771.689840160396;4833.78247978841;4954.826581069663;5024.522670346311;5033.672811267265;3728.664420202188;3741.88774336769;3744.082699738568;3747.156889699823;3752.2683076800035;3723;3726.31063253299;3734.253149370123;3747.3254814133447;3748.2878253131953;3552.5559305086863;3612.6567976042234;3630.5824830144606;3631.055925459373;3702.555693316806;3579.46308053794;3602.3875941090078;3642.471841582969;3680.969328588;3683.373826449483;3161.162291992624;3166.778325517215;3174.5714328565577;3187.9043628690943;3201.16769639893;4869.366982292315;4895.363602731622;4918.3360246367;4925.507449255046;4925.729084900329;4037.777922214277;4238.300680387732;4299.54854740254;4305.547917468683;4313.741175529447;5130.1535246684625;5231.60762942777;5237.633365664405;5240.711760853152;5242.737863106868;3447.1382154461385;3451.7928924264374;3451.9309613807836;3452.757007477306;3457.6542345765474;5393.865153371158;5414.648047876875;5477.75350109246;5504.422035686275;5515.25544051556;3406.347921258188;3488.3720930232803;3524.7708898921487;3528.7529872909163;3531.4173161428075;3287.293231955141;3528.5060091587197;3551.5027896094566;3561.661642143992;3580.5882323532605;4217.578242175758;4223.873283801491;4227.788610569459;4231.449911511484;4237.978810105929;5273.1299335609965;5301.575873930076;5305.814296499604;5309.283246761715;5314.946850531445;1177.6996865799167;1195.6711904226406;1215.1979693402488;1216.397883047897;1219.4390580333147;712.707789806177;751.4964973467789;753.6118898767139;769.0924708843618;769.4844454215689;4800.3279540864305;4878.365812444359;4923;4983.227599722049;5007.223880298559;5401.648892821954;5430.619856609999;5458.8908221835345;5460.60754507598;5477.370102438271;4989.25161225819;4997.900041999213;5019.698818070928;5027.296178535008;5042.4957504250015;2750.051232324845;2859.9428011440073;2900.912972610825;2905.535114381689;2907.4735763724216;5625.606207565513;5635.5773317001385;5636.85907852307;5647.237622920935;5648.293963254593;5594.944050559442;5602.327720673546;5605.663660180402;5614.494695477427;5618.410066942994;5401.648892821954;5634.661920284714;5649.237352957381;5653.491185793216;5682;5433.8369848904595;5474.507294343528;5496.697681627549;5502.614816962934;5529.944700552943;5421.7560209791445;5425.267588875451;5425.891482170414;5440.809571664972;5444.918326225189;5579.7489112990825;5671.801486947937;5698.458646428583;5699.601027928005;5703.429657034263;3345.6152542456957;3489.1800426900168;3505.491703703;3545.450455179451;3566.946495802617;5421.05131601976;5427.375851777097;5435.130379139318;5443.591730620298;5448.509634132947;5126.4617215191865;5219.138842091164;5258.553022992935;5265.578753699771;5272;5239.764210610608;5244.76398562058;5257.342832145981;5258.395284542174;5272.841814745564;4910.656254062182;5136.486351364983;5147.510986456281;5152.536271735562;5157.716325602128;5266.8419947401635;5279.577633789365;5281.630285879952;5294.97352513235;5295.443978382368;5033.2701758245585;5088.262201980766;5157.6647517911215;5211.973940130275;5247.239150323258 10:47:56 INFO - PROCESS | 776 | |5;yui.html;582.0774073094145;582.4728620598358;592.7777083593652;600.2466904035438;605.6124080588427;13420.932895335522;14570.56288311351;14836.258187090643;15003.324850381732;15008.624784380369;12037.217580857237;13453.596392108228;13459.125156864797;13627.863721362783;14273.571792846205;603.5111559636708;618.9350118237588;619.944205021548;622.7477871462065;623.2987888625297;616.7378863982811;617.1051974636772;617.7590739611555;618.1284389011472;618.3383779356068;642.7621779941405;649.6849028221333;649.8537828988487;651.4007113455632;652.6606164794304;628.2963081348896;645.2128403347908;648.1185587600859;648.4229036157823;649.1917562634527;427.90586071064314;465.18824650983913;468.99531004689857;472.89359894023914;473.94075740532435;13046.739065218737;13621.727565448635;13812.447502099903;13984.160950343012;14033;13216.207027578375;13671.974601904896;13822.792658110136;13962.93018534911;14410.135391876416;11253.437328133643;11308.660740177807;11423.714407139889;11464.942675286653;11658.59194928182;3156.210947263184;3371.2414706691043;3380.6281309055985;3486.6861982421706;3512.0868574170645;72709.27290727131;73268.26731732668;76493;76518.23481765167;77303.22696773017;9345;9792;10060.344096111277;10065.094141527297;10089.394636321844;17162.742558861628;18152.45542633723;18393.172307246205;18403.355882544314;18510.629787404447;7185.353318201387;7274.30894065063;7364.889526657104;7506.24937506245;7557.130929943127;3527.700145487611;3596.5318091599825;3752.2307926874923;3774.905627359338;3786.905327366783;2703.958975794333;2731.658542682165;2747.7939154563087;2793.69269380368;2806.0880213930554;437.5471387114321;437.8183054032541;440.27354864473875;440.8098135035417;441.2124358020917;621.9657918814418;623.4856243599049;624.2540164503465;638.4445532386853;643.1638869469671;8470.788230294293;9094.272458203328;9281.535923203948;9626.518674066268;9850.50747462624;581.6975172910063;587.4624718382632;622.3620788691627;670.396643021275;674.4503229867643;4401.031773009932;4463.680242530639;4619.792109355087;4658.464276608166;4673.439187297478;64970.675146623966;74853.625731871;103656;111039;111525;76509.08072925726;93408;93674;97127.51436242774;97462.51268743612;84172.6870484716;87273.56363218144;90941.54529227312;92208;93676.53161734284;8174.918250817654;8288.254057134645;8366.876079943093;8495.915040849512;8537.274331681629;52452;52508.474915251885;52674.209886850964;53183.93632127413;53376.73311633417;73621.6318918402;74723;75880.62059689667;77140;78264.21735782569;10569.788604228026;10858.077063449695;10900.78198436011;10927.562897484011;11344.943275283571;13241.27173005494;13404.865951340751;13467.3939672713;13569.72860542764;13910.58268251954;12107;12484.188527745668;12493.312867791996;12521.186122901981;12592.74814503723;12197.878021219674;12617.810732839198;12760.489580416679;12830.743385132431;12850.807237891253;11615.361155136548;12233;12333.260004399765;12444.128910976146;12697.111202215756;10738;11031.72420689457;11142.554297828321;11194.216404851582;11353.148513861328;9999.450030248116;10253.436061016715;10368.066873981377;10530.684079477627;10702.946485267525 10:47:56 INFO - PROCESS | 776 | __end_tp_report 10:47:56 INFO - PROCESS | 776 | __start_cc_report 10:47:56 INFO - PROCESS | 776 | _x_x_mozilla_cycle_collect,1266 10:47:56 INFO - PROCESS | 776 | __end_cc_report 10:47:56 INFO - PROCESS | 776 | __startTimestamp1476985676772__endTimestamp 10:47:56 INFO - PROCESS | 776 | 10:47:56 INFO - PROCESS | 776 | ------- Summary: start ------- 10:47:56 INFO - PROCESS | 776 | Number of tests: 6 10:47:56 INFO - PROCESS | 776 | 10:47:56 INFO - PROCESS | 776 | [#0] dojo.html Cycles:165 Average:4549.03 Median:4789.04 stddev:570.85 (11.9%) stddev-sans-first:554.56 10:47:56 INFO - PROCESS | 776 | Values: 2733.9 2755.9 2758.8 2767.8 2775.8 4647.8 4717.7 4798.8 4815.4 4825.0 4653.1 4678.7 4690.2 4696.8 4787.4 4087.7 4157.5 4158.2 4167.4 4192.5 4051.6 4131.0 4173.8 4180.2 4223.5 4120.8 4126.8 4127.7 4205.9 4225.1 3951.4 4005.6 4085.6 4154.6 4177.0 3977.3 4092.1 4103.7 4141.2 4149.7 4531.7 4561.4 4621.1 4642.4 4664.0 4586.8 4778.9 4806.5 4815.8 4884.8 4874.2 4892.7 4904.6 4910.2 4958.7 4444.9 4477.3 4564.4 4584.1 4584.6 4740.8 4893.9 4938.3 5009.6 5067.9 4722.2 4748.5 4756.2 4756.5 4792.2 4908.0 4911.0 4917.6 4925.0 4935.8 4844.0 4851.6 4854.7 4859.5 4872.2 4869.7 4902.5 4915.1 4915.7 4917.8 3240.2 3249.8 3347.8 3349.1 3349.8 3003.4 3062.1 3068.9 3073.5 3075.0 4560.6 4620.3 4638.9 4671.7 4674.7 4853.1 4920.5 4938.6 5029.4 5047.3 4534.9 4584.5 4613.2 4708.4 4719.8 4111.3 4161.0 4210.2 4211.1 4245.6 5100.8 5115.6 5163.7 5254.4 5254.9 4903.5 4934.8 5082.8 5105.4 5105.7 4918.2 5054.8 5061.8 5066.8 5132.1 4822.0 4900.4 4915.5 4934.8 4961.5 4903.8 4934.5 5020.2 5037.1 5051.0 4746.0 4789.0 4797.6 4810.5 4837.8 4821.4 4832.8 4860.3 4906.8 4928.4 4674.3 4723.1 4814.9 4820.7 4825.4 4800.5 4833.7 4840.3 4894.6 4954.8 4809.0 4819.0 4845.2 4891.1 4915.8 10:47:56 INFO - PROCESS | 776 | 10:47:56 INFO - PROCESS | 776 | [#1] ext.html Cycles:180 Average:53555.62 Median:37803.91 stddev:108824.33 (287.9%) stddev-sans-first:109056.70 10:47:56 INFO - PROCESS | 776 | Values: 499.6 521.7 522.5 542.7 543.1 28916.7 29644.6 29814.6 29872.1 29880.6 29503.0 30013.8 38942.4 39516.4 39980.0 7799.5 7915.4 7926.9 7939.5 7947.2 7681.1 7763.8 7764.8 7848.4 7871.1 6790.2 6843.4 6852.5 6993.5 7976.6 6880.8 9523.5 9715.4 9719.2 10014.9 9620.3 9658.7 9701.9 9702.0 9706.2 36602.6 37384.0 37671.2 37889.0 37898.8 2725.7 2725.8 2774.2 3014.7 3391.8 28351.9 29346.7 29804.1 29959.6 30387.8 3229.5 3259.4 3264.3 3267.8 3350.9 314897.0 315925.0 316779.0 317444.0 317963.0 49818.3 50016.5 50319.0 50348.2 50410.2 28646.9 29337.3 51488.0 51550.5 51875.2 12708.4 13091.6 13116.9 13127.5 13150.9 8801.6 9618.2 9646.5 9678.8 9708.4 16101.9 16106.8 16710.1 16744.6 16776.4 5422.2 6410.5 6510.7 6556.3 6662.5 701.2 737.4 782.6 825.6 878.8 25991.7 27121.6 27296.0 27416.9 27596.0 808.3 849.6 860.8 878.3 879.6 13220.9 13690.4 13712.9 13915.2 14099.6 446267.0 450619.0 451394.6 459405.0 461543.0 46339.8 46382.5 46410.3 46673.3 46697.8 923.0 968.3 1062.4 1068.0 1071.4 26704.1 27428.9 27576.3 28096.0 28177.7 40722.0 40944.6 40956.8 41548.8 41711.6 385622.0 386724.0 387855.0 436766.0 587689.0 36980.0 37423.4 37454.8 37512.4 37718.8 41153.0 42177.0 42483.6 42748.8 42959.0 35556.8 35672.8 35774.5 35793.3 36199.0 35900.6 35968.0 36172.5 37175.4 37698.6 33519.3 33740.7 34127.1 34356.0 34439.7 31236.0 31714.0 32155.8 32437.4 32817.7 24557.3 24766.0 25025.7 25074.4 25272.0 10:47:56 INFO - PROCESS | 776 | 10:47:56 INFO - PROCESS | 776 | [#2] jquery.html Cycles:180 Average:26215.65 Median:43696.39 stddev:69211.63 (158.4%) stddev-sans-first:69378.38 10:47:56 INFO - PROCESS | 776 | Values: 274.4 294.3 297.4 307.3 307.9 12817.0 17290.0 17706.9 17988.2 19189.6 16470.2 16704.9 16834.7 16888.3 17103.7 5082.2 5287.8 5290.6 5299.5 5301.3 4978.5 5247.8 5256.2 5256.3 5273.9 5009.4 5193.3 5207.3 5209.3 5212.1 4931.2 4982.5 5067.1 5178.6 5195.8 4472.8 4484.4 4491.2 4500.6 4500.8 22259.7 22287.1 22428.8 22442.6 22501.2 1686.2 1751.6 1887.3 2419.6 2496.4 13177.4 13491.6 14059.9 14264.9 14430.1 1650.3 1682.9 1705.7 1723.2 1769.1 82038.6 82228.2 82898.0 82964.0 83136.6 8493.7 8629.5 8634.7 8707.7 8800.2 10025.8 10476.7 10495.5 10714.9 10751.5 6383.0 6606.1 6617.9 6727.2 6768.7 6724.6 6767.9 6789.5 6909.0 6910.8 5409.9 5546.6 5652.2 5658.5 5659.8 1448.1 1485.5 1497.9 1515.1 1518.8 743.6 806.5 844.6 876.5 891.8 18436.7 19348.7 19493.6 19499.0 19822.3 815.0 840.7 846.5 868.4 881.3 10083.7 10308.2 10597.9 10683.9 10723.0 378159.0 379766.0 382335.0 456030.0 462001.0 91544.0 96076.5 103244.0 105175.0 105251.5 352.3 360.1 365.2 366.6 375.6 8699.6 8929.2 8945.5 9347.3 9507.5 37663.2 40883.0 42793.0 44599.8 47401.8 50956.7 53294.3 63732.0 64294.7 65258.7 6906.3 6915.0 6988.5 7012.8 7170.2 9016.5 12098.9 12237.1 12292.6 12655.8 10190.9 10238.6 10290.0 10295.2 10486.9 9608.7 10293.8 10388.1 10433.7 10549.1 10488.6 10503.3 10546.1 10637.9 10706.4 7061.9 7082.2 7083.8 7230.0 7237.3 9658.7 10210.5 10280.2 10329.6 10478.3 10:47:56 INFO - PROCESS | 776 | 10:47:56 INFO - PROCESS | 776 | [#3] mootools.html Cycles:180 Average:4503.05 Median:5310.06 stddev:2513.24 (47.3%) stddev-sans-first:2507.66 10:47:56 INFO - PROCESS | 776 | Values: 1151.0 1151.4 1237.2 1440.7 1472.8 6620.6 7064.5 7076.2 7106.1 7110.2 6233.4 6443.4 6487.1 6701.9 6856.5 1889.2 1919.9 1924.2 1926.0 1926.5 1909.0 1912.8 1918.8 1937.1 1941.9 1659.3 1669.8 1855.1 1890.6 1900.6 1776.6 1892.7 1901.6 1909.3 1917.0 1798.1 1801.1 1813.8 1827.6 1854.7 6780.1 6788.7 6858.4 6872.5 6883.4 4053.1 4141.9 4149.5 4157.3 4170.4 2602.9 2658.9 2662.9 2688.7 2696.4 838.1 863.1 869.4 870.5 873.7 8906.6 8947.7 8978.2 9051.4 9052.9 3675.1 3697.3 3752.7 3773.9 3798.6 7417.9 7526.9 7544.7 7589.3 7716.5 4155.6 4157.2 4178.3 4185.8 4187.0 3889.6 3897.1 3907.2 3907.6 3908.6 2979.6 3009.4 3031.2 3080.5 3114.0 969.4 984.9 986.4 990.7 993.6 747.5 766.6 790.6 800.9 816.0 4413.9 4450.1 4453.7 4614.4 4711.5 327.0 331.2 334.2 347.8 350.5 2914.1 2927.8 2996.3 3104.2 3271.1 7973.2 8000.2 8133.5 8273.0 8383.5 8211.8 8413.6 8423.9 8433.8 8434.3 6411.7 6512.4 6531.0 6532.4 6567.7 4583.0 4669.4 4758.8 4887.9 4920.4 7699.0 7801.6 7884.4 7928.8 7954.6 7742.7 7922.1 8051.9 8060.4 8066.7 5868.6 6183.0 6209.3 6217.1 6229.2 6663.6 6758.4 6787.9 6798.8 6805.5 5231.9 5399.7 6170.6 6236.7 6239.3 5933.3 6043.7 6144.5 6197.3 6199.1 5388.2 5732.2 5752.2 5755.6 5758.7 4070.1 4084.2 4126.3 4132.9 4288.0 3853.6 4048.5 4092.7 4095.8 4166.3 10:47:56 INFO - PROCESS | 776 | 10:47:56 INFO - PROCESS | 776 | [#4] prototype.html Cycles:180 Average:4349.17 Median:5033.47 stddev:1379.23 (27.4%) stddev-sans-first:1348.15 10:47:56 INFO - PROCESS | 776 | Values: 238.8 241.2 241.6 245.7 247.9 5017.4 5109.6 5113.4 5124.7 5129.9 4771.7 4833.8 4954.8 5024.5 5033.7 3728.7 3741.9 3744.1 3747.2 3752.3 3723.0 3726.3 3734.3 3747.3 3748.3 3552.6 3612.7 3630.6 3631.1 3702.6 3579.5 3602.4 3642.5 3681.0 3683.4 3161.2 3166.8 3174.6 3187.9 3201.2 4869.4 4895.4 4918.3 4925.5 4925.7 4037.8 4238.3 4299.5 4305.5 4313.7 5130.2 5231.6 5237.6 5240.7 5242.7 3447.1 3451.8 3451.9 3452.8 3457.7 5393.9 5414.6 5477.8 5504.4 5515.3 3406.3 3488.4 3524.8 3528.8 3531.4 3287.3 3528.5 3551.5 3561.7 3580.6 4217.6 4223.9 4227.8 4231.4 4238.0 5273.1 5301.6 5305.8 5309.3 5314.9 1177.7 1195.7 1215.2 1216.4 1219.4 712.7 751.5 753.6 769.1 769.5 4800.3 4878.4 4923.0 4983.2 5007.2 5401.6 5430.6 5458.9 5460.6 5477.4 4989.3 4997.9 5019.7 5027.3 5042.5 2750.1 2859.9 2900.9 2905.5 2907.5 5625.6 5635.6 5636.9 5647.2 5648.3 5594.9 5602.3 5605.7 5614.5 5618.4 5401.6 5634.7 5649.2 5653.5 5682.0 5433.8 5474.5 5496.7 5502.6 5529.9 5421.8 5425.3 5425.9 5440.8 5444.9 5579.7 5671.8 5698.5 5699.6 5703.4 3345.6 3489.2 3505.5 3545.5 3566.9 5421.1 5427.4 5435.1 5443.6 5448.5 5126.5 5219.1 5258.6 5265.6 5272.0 5239.8 5244.8 5257.3 5258.4 5272.8 4910.7 5136.5 5147.5 5152.5 5157.7 5266.8 5279.6 5281.6 5295.0 5295.4 5033.3 5088.3 5157.7 5212.0 5247.2 10:47:56 INFO - PROCESS | 776 | 10:47:56 INFO - PROCESS | 776 | [#5] yui.html Cycles:180 Average:19481.55 Median:2421.12 stddev:28329.31 (1170.1%) stddev-sans-first:28373.24 10:47:56 INFO - PROCESS | 776 | Values: 582.1 582.5 592.8 600.2 605.6 13420.9 14570.6 14836.3 15003.3 15008.6 12037.2 13453.6 13459.1 13627.9 14273.6 603.5 618.9 619.9 622.7 623.3 616.7 617.1 617.8 618.1 618.3 642.8 649.7 649.9 651.4 652.7 628.3 645.2 648.1 648.4 649.2 427.9 465.2 469.0 472.9 473.9 13046.7 13621.7 13812.4 13984.2 14033.0 13216.2 13672.0 13822.8 13962.9 14410.1 11253.4 11308.7 11423.7 11464.9 11658.6 3156.2 3371.2 3380.6 3486.7 3512.1 72709.3 73268.3 76493.0 76518.2 77303.2 9345.0 9792.0 10060.3 10065.1 10089.4 17162.7 18152.5 18393.2 18403.4 18510.6 7185.4 7274.3 7364.9 7506.2 7557.1 3527.7 3596.5 3752.2 3774.9 3786.9 2704.0 2731.7 2747.8 2793.7 2806.1 437.5 437.8 440.3 440.8 441.2 622.0 623.5 624.3 638.4 643.2 8470.8 9094.3 9281.5 9626.5 9850.5 581.7 587.5 622.4 670.4 674.5 4401.0 4463.7 4619.8 4658.5 4673.4 64970.7 74853.6 103656.0 111039.0 111525.0 76509.1 93408.0 93674.0 97127.5 97462.5 84172.7 87273.6 90941.5 92208.0 93676.5 8174.9 8288.3 8366.9 8495.9 8537.3 52452.0 52508.5 52674.2 53183.9 53376.7 73621.6 74723.0 75880.6 77140.0 78264.2 10569.8 10858.1 10900.8 10927.6 11344.9 13241.3 13404.9 13467.4 13569.7 13910.6 12107.0 12484.2 12493.3 12521.2 12592.7 12197.9 12617.8 12760.5 12830.7 12850.8 11615.4 12233.0 12333.3 12444.1 12697.1 10738.0 11031.7 11142.6 11194.2 11353.1 9999.5 10253.4 10368.1 10530.7 10702.9 10:47:56 INFO - PROCESS | 776 | -------- Summary: end -------- 10:47:56 INFO - PROCESS | 776 | 10:47:57 INFO - TEST-INFO | 776: exit 0 10:47:58 INFO - TEST-OK | dromaeo_css | took 1120494ms 10:47:58 INFO - TEST-START | kraken 10:47:58 INFO - operating with platform_type : w7_ 10:47:58 INFO - Initialising browser for kraken test... 10:47:58 INFO - TEST-INFO | started process 3440 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpknjjbw\profile http://localhost:49695/getInfo.html) 10:48:02 INFO - PROCESS | 3440 | __metrics Screen width/height:1600/1200 10:48:02 INFO - PROCESS | 3440 | colorDepth:24 10:48:02 INFO - PROCESS | 3440 | Browser inner width/height: 1010/674 10:48:02 INFO - PROCESS | 3440 | __metrics 10:48:03 INFO - PROCESS | 3440 | 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:48:03 INFO - PROCESS | 3440 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:48:03 INFO - PROCESS | 3440 | console.error: 10:48:03 INFO - PROCESS | 3440 | Message: Error: Connection closed before committing the transaction. 10:48:03 INFO - PROCESS | 3440 | Stack: 10:48:03 INFO - PROCESS | 3440 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:937:23 10:48:03 INFO - PROCESS | 3440 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 10:48:03 INFO - PROCESS | 3440 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 10:48:03 INFO - PROCESS | 3440 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 10:48:03 INFO - PROCESS | 3440 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 10:48:03 INFO - PROCESS | 3440 | TaskImpl_run@resource://gre/modules/Task.jsm:327:15 10:48:03 INFO - PROCESS | 3440 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9 10:48:03 INFO - PROCESS | 3440 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 10:48:03 INFO - PROCESS | 3440 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5 10:48:03 INFO - PROCESS | 3440 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 10:48:03 INFO - PROCESS | 3440 | Barrier/this.client.addBlocker/promise13.1% 10:49:01 INFO - TinderboxPrint: I/O read bytes / time
84,127,232 / 1,113,770 10:49:01 INFO - TinderboxPrint: I/O write bytes / time
484,612,608 / 3,655,310 10:49:01 INFO - TinderboxPrint: CPU idle
8,264.7 (86.9%) 10:49:01 INFO - TinderboxPrint: CPU user
1,210.7 (12.7%) 10:49:01 INFO - install - Wall time: 3s; CPU: 11.0%; Read bytes: 0; Write bytes: 34601984; Read time: 0; Write time: 46450 10:49:01 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 84127232; Write bytes: 412740608; Read time: 1113770; Write time: 3405790 10:49:01 INFO - Running post-run listener: _upload_blobber_files 10:49:01 INFO - Blob upload gear active. 10:49:01 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir. 10:49:01 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:49:01 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:49:01 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:49:02 INFO - (blobuploader) - INFO - Open directory for files ... 10:49:02 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log ... 10:49:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:49:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:49:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:49:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:49:02 INFO - (blobuploader) - INFO - Done attempting. 10:49:02 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log ... 10:49:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:49:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:49:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:49:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:49:03 INFO - (blobuploader) - INFO - Done attempting. 10:49:03 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ... 10:49:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:49:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:49:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:49:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:49:11 INFO - (blobuploader) - INFO - Done attempting. 10:49:11 INFO - (blobuploader) - INFO - Iteration through files over. 10:49:11 INFO - Return code: 0 10:49:11 INFO - rmtree: C:\slave\test\build\uploaded_files.json 10:49:11 INFO - Using _rmtree_windows ... 10:49:11 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1 10:49:11 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/85e37403a20430556c0ac7813563cc075420f19bc67d06a847ddfc537fcfb038349b63920c8c6e5b68dd537eb863ff5642a871babbe66203450e9eaa5219a093", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a65e6b207fa7df2849e21d709070dd5ea263921d9046c561d9f30ed3e2ee77facd4e101f0e4cd17791098ce2f05b8be805629114b05705364e85be28fbc90ea9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/674c19f2bcb58bf8f24211f772f90f3559376c472e5df8f987a4f4ad15426c35ef68273d336428b3c8611e3a6592fde345db132b837e397697dc3e6dccb41ea5"} 10:49:11 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files 10:49:11 INFO - Writing to file C:\slave\test\properties\blobber_files 10:49:11 INFO - Contents: 10:49:11 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/85e37403a20430556c0ac7813563cc075420f19bc67d06a847ddfc537fcfb038349b63920c8c6e5b68dd537eb863ff5642a871babbe66203450e9eaa5219a093", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a65e6b207fa7df2849e21d709070dd5ea263921d9046c561d9f30ed3e2ee77facd4e101f0e4cd17791098ce2f05b8be805629114b05705364e85be28fbc90ea9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/674c19f2bcb58bf8f24211f772f90f3559376c472e5df8f987a4f4ad15426c35ef68273d336428b3c8611e3a6592fde345db132b837e397697dc3e6dccb41ea5"} 10:49:11 INFO - Running post-run listener: copy_logs_to_upload_dir 10:49:11 INFO - Copying logs to upload dir... 10:49:11 INFO - mkdir: C:\slave\test\build\upload\logs 10:49:11 INFO - Copying logs to upload dir... 10:49:11 INFO - Using _rmtree_windows ... 10:49:11 INFO - Using _rmtree_windows ... 10:49:11 INFO - Using _rmtree_windows ... 10:49:11 INFO - Using _rmtree_windows ... 10:49:11 INFO - Using _rmtree_windows ... 10:49:11 INFO - Using _rmtree_windows ... 10:49:11 INFO - Using _rmtree_windows ... program finished with exit code 0 elapsedTime=1499.119000 ========= master_lag: 1.24 ========= ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 25 mins, 0 secs) (at 2016-10-20 10:49:11.561331) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-20 10:49:11.567577) ========= '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-107 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-107 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-107 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/85e37403a20430556c0ac7813563cc075420f19bc67d06a847ddfc537fcfb038349b63920c8c6e5b68dd537eb863ff5642a871babbe66203450e9eaa5219a093", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a65e6b207fa7df2849e21d709070dd5ea263921d9046c561d9f30ed3e2ee77facd4e101f0e4cd17791098ce2f05b8be805629114b05705364e85be28fbc90ea9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/674c19f2bcb58bf8f24211f772f90f3559376c472e5df8f987a4f4ad15426c35ef68273d336428b3c8611e3a6592fde345db132b837e397697dc3e6dccb41ea5"} build_url:https://queue.taskcluster.net/v1/task/K0tqtoNwRYeAb-YN4Y3fRQ/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/K0tqtoNwRYeAb-YN4Y3fRQ/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/85e37403a20430556c0ac7813563cc075420f19bc67d06a847ddfc537fcfb038349b63920c8c6e5b68dd537eb863ff5642a871babbe66203450e9eaa5219a093", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a65e6b207fa7df2849e21d709070dd5ea263921d9046c561d9f30ed3e2ee77facd4e101f0e4cd17791098ce2f05b8be805629114b05705364e85be28fbc90ea9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/674c19f2bcb58bf8f24211f772f90f3559376c472e5df8f987a4f4ad15426c35ef68273d336428b3c8611e3a6592fde345db132b837e397697dc3e6dccb41ea5"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-20 10:49:11.680096) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 10:49:11.680448) ========= '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-107 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-107 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-107 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.49 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 10:49:12.274572) ========= ========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-10-20 10:49:12.274848) ========= ========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-10-20 10:49:12.747581) ========= ========= Total master_lag: 1.87 =========