builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0288 starttime: 1472692783.97 results: success (0) buildid: 20160831162521 builduid: c44e13f57ae445188117e9958c36ee04 revision: adcf904e2d2b25bf38ded04a90862e2631ecabf4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-31 18:19:43.970078) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-31 18:19:43.970632) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-31 18:19:43.970952) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-31 18:19:44.007149) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-08-31 18:19:44.007553) ========= 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 /builds/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: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.6Ce2dIxkcZ/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.9S2VLVFUD1/Listeners TMPDIR=/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-08-31 18:19:44-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.3M=0.001s 2016-08-31 18:19:44 (18.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.087470 ========= master_lag: 4.09 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-08-31 18:19:48.182890) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 18:19:48.183276) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.6Ce2dIxkcZ/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.9S2VLVFUD1/Listeners TMPDIR=/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.026120 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 18:19:48.242629) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-08-31 18:19:48.242981) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev adcf904e2d2b25bf38ded04a90862e2631ecabf4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev adcf904e2d2b25bf38ded04a90862e2631ecabf4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.6Ce2dIxkcZ/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.9S2VLVFUD1/Listeners TMPDIR=/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-08-31 18:19:48,334 truncating revision to first 12 chars 2016-08-31 18:19:48,334 Setting DEBUG logging. 2016-08-31 18:19:48,334 attempt 1/10 2016-08-31 18:19:48,334 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/adcf904e2d2b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-31 18:19:50,414 unpacking tar archive at: autoland-adcf904e2d2b/testing/mozharness/ program finished with exit code 0 elapsedTime=2.344399 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-08-31 18:19:50.612718) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-31 18:19:50.613182) ========= script_repo_revision: adcf904e2d2b25bf38ded04a90862e2631ecabf4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-31 18:19:50.613746) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-31 18:19:50.614135) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-31 18:19:50.634019) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-08-31 18:19:50.634553) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.6Ce2dIxkcZ/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.9S2VLVFUD1/Listeners TMPDIR=/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 18:19:50 INFO - MultiFileLogger online at 20160831 18:19:50 in /builds/slave/test 18:19:50 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 18:19:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:19:50 INFO - {'append_to_log': False, 18:19:50 INFO - 'base_work_dir': '/builds/slave/test', 18:19:50 INFO - 'blob_upload_branch': 'Autoland', 18:19:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:19:50 INFO - 'branch': 'Autoland', 18:19:50 INFO - 'buildbot_json_path': 'buildprops.json', 18:19:50 INFO - 'config_files': ('talos/mac_config.py',), 18:19:50 INFO - 'default_actions': ('clobber', 18:19:50 INFO - 'read-buildbot-config', 18:19:50 INFO - 'download-and-extract', 18:19:50 INFO - 'populate-webroot', 18:19:50 INFO - 'create-virtualenv', 18:19:50 INFO - 'install', 18:19:50 INFO - 'run-tests'), 18:19:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:19:50 INFO - 'download_minidump_stackwalk': True, 18:19:50 INFO - 'download_symbols': 'ondemand', 18:19:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:19:50 INFO - 'tooltool.py': '/tools/tooltool.py', 18:19:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:19:50 INFO - '/tools/misc-python/virtualenv.py')}, 18:19:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:19:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:19:50 INFO - 'installer_path': 'installer.exe', 18:19:50 INFO - 'log_level': 'info', 18:19:50 INFO - 'log_name': 'talos', 18:19:50 INFO - 'log_to_console': True, 18:19:50 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:19:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:19:50 INFO - 'opt_config_files': (), 18:19:50 INFO - 'pip_index': False, 18:19:50 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:19:50 INFO - 'cmd': ('bash', 18:19:50 INFO - '-c', 18:19:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:19:50 INFO - 'enabled': True, 18:19:50 INFO - 'halt_on_failure': False, 18:19:50 INFO - 'name': 'check_screen_resolution'},), 18:19:50 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:19:50 INFO - 'cmd': ('bash', 18:19:50 INFO - '-c', 18:19:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:19:50 INFO - 'enabled': True, 18:19:50 INFO - 'halt_on_failure': False, 18:19:50 INFO - 'name': 'check_screen_resolution'},), 18:19:50 INFO - 'run_cmd_checks_enabled': True, 18:19:50 INFO - 'sps_profile': False, 18:19:50 INFO - 'sps_profile_interval': 0, 18:19:50 INFO - 'suite': 'dromaeojs', 18:19:50 INFO - 'system_bits': '32', 18:19:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:19:50 INFO - 'title': 't-yosemite-r7-0288', 18:19:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:19:50 INFO - 'use_talos_json': True, 18:19:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:19:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:19:50 INFO - 'work_dir': 'build'} 18:19:50 INFO - [mozharness: 2016-09-01 01:19:50.831789Z] Running clobber step. 18:19:50 INFO - Running pre-action listener: _resource_record_pre_action 18:19:50 INFO - Running main action method: clobber 18:19:50 INFO - rmtree: /builds/slave/test/build 18:19:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:19:52 INFO - Running post-action listener: _resource_record_post_action 18:19:52 INFO - [mozharness: 2016-09-01 01:19:52.979695Z] Finished clobber step (success) 18:19:52 INFO - [mozharness: 2016-09-01 01:19:52.979855Z] Running read-buildbot-config step. 18:19:52 INFO - Running pre-action listener: _resource_record_pre_action 18:19:52 INFO - Running main action method: read_buildbot_config 18:19:52 INFO - Using buildbot properties: 18:19:52 INFO - { 18:19:52 INFO - "project": "", 18:19:52 INFO - "product": "firefox", 18:19:52 INFO - "script_repo_revision": "production", 18:19:52 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 18:19:52 INFO - "repository": "", 18:19:52 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 18:19:52 INFO - "buildid": "20160831162521", 18:19:52 INFO - "pgo_build": "False", 18:19:52 INFO - "basedir": "/builds/slave/test", 18:19:52 INFO - "buildnumber": 170, 18:19:52 INFO - "slavename": "t-yosemite-r7-0288", 18:19:52 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 18:19:52 INFO - "platform": "yosemite_r7", 18:19:52 INFO - "scheduler": "tests-autoland-macosx64-talos", 18:19:52 INFO - "branch": "autoland", 18:19:52 INFO - "repo_path": "integration/autoland", 18:19:52 INFO - "revision": "adcf904e2d2b25bf38ded04a90862e2631ecabf4", 18:19:52 INFO - "stage_platform": "macosx64", 18:19:52 INFO - "builduid": "c44e13f57ae445188117e9958c36ee04", 18:19:52 INFO - "slavebuilddir": "test" 18:19:52 INFO - } 18:19:52 INFO - Found installer url https://queue.taskcluster.net/v1/task/SqFZLK2kRYqCHNmgJFZ1YA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 18:19:52 INFO - Running post-action listener: _resource_record_post_action 18:19:52 INFO - [mozharness: 2016-09-01 01:19:52.983197Z] Finished read-buildbot-config step (success) 18:19:52 INFO - [mozharness: 2016-09-01 01:19:52.983321Z] Running download-and-extract step. 18:19:52 INFO - Running pre-action listener: _resource_record_pre_action 18:19:52 INFO - Running main action method: download_and_extract 18:19:52 INFO - mkdir: /builds/slave/test/build/tests 18:19:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:19:52 INFO - trying https://queue.taskcluster.net/v1/task/SqFZLK2kRYqCHNmgJFZ1YA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 18:19:52 INFO - Downloading https://queue.taskcluster.net/v1/task/SqFZLK2kRYqCHNmgJFZ1YA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 18:19:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SqFZLK2kRYqCHNmgJFZ1YA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 18:19:53 INFO - Downloaded 1199 bytes. 18:19:53 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 18:19:53 INFO - Using the following test package requirements: 18:19:53 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 18:19:53 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:19:53 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 18:19:53 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:19:53 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 18:19:53 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:19:53 INFO - u'jsshell-mac.zip'], 18:19:53 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:19:53 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 18:19:53 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 18:19:53 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:19:53 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 18:19:53 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:19:53 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 18:19:53 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:19:53 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 18:19:53 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:19:53 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 18:19:53 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:19:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:19:53 INFO - trying https://queue.taskcluster.net/v1/task/SqFZLK2kRYqCHNmgJFZ1YA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 18:19:53 INFO - Downloading https://queue.taskcluster.net/v1/task/SqFZLK2kRYqCHNmgJFZ1YA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 18:19:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SqFZLK2kRYqCHNmgJFZ1YA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:19:54 INFO - Downloaded 16394734 bytes. 18:19:54 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 18:20:01 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 18:20:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:01 INFO - trying https://queue.taskcluster.net/v1/task/SqFZLK2kRYqCHNmgJFZ1YA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 18:20:01 INFO - Downloading https://queue.taskcluster.net/v1/task/SqFZLK2kRYqCHNmgJFZ1YA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 18:20:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SqFZLK2kRYqCHNmgJFZ1YA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:20:02 INFO - Downloaded 16394734 bytes. 18:20:02 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 18:20:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:09 INFO - trying https://queue.taskcluster.net/v1/task/SqFZLK2kRYqCHNmgJFZ1YA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 18:20:09 INFO - Downloading https://queue.taskcluster.net/v1/task/SqFZLK2kRYqCHNmgJFZ1YA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 18:20:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SqFZLK2kRYqCHNmgJFZ1YA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:20:10 INFO - Downloaded 14413998 bytes. 18:20:10 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 18:20: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')]} 18:20:10 INFO - trying https://queue.taskcluster.net/v1/task/SqFZLK2kRYqCHNmgJFZ1YA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 18:20:10 INFO - Downloading https://queue.taskcluster.net/v1/task/SqFZLK2kRYqCHNmgJFZ1YA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 18:20:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SqFZLK2kRYqCHNmgJFZ1YA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 18:20:14 INFO - Downloaded 111577343 bytes. 18:20:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SqFZLK2kRYqCHNmgJFZ1YA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 18:20:14 INFO - mkdir: /builds/slave/test/properties 18:20:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:20:14 INFO - Writing to file /builds/slave/test/properties/build_url 18:20:14 INFO - Contents: 18:20:14 INFO - build_url:https://queue.taskcluster.net/v1/task/SqFZLK2kRYqCHNmgJFZ1YA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 18:20:14 INFO - Running post-action listener: _resource_record_post_action 18:20:14 INFO - Running post-action listener: set_extra_try_arguments 18:20:14 INFO - [mozharness: 2016-09-01 01:20:14.706685Z] Finished download-and-extract step (success) 18:20:14 INFO - [mozharness: 2016-09-01 01:20:14.706851Z] Running populate-webroot step. 18:20:14 INFO - Running pre-action listener: _resource_record_pre_action 18:20:14 INFO - Running main action method: populate_webroot 18:20:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:20:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:20:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:20:14 INFO - '--tppagecycles', 18:20:14 INFO - '7'], 18:20:14 INFO - 'tests': ['tsvgm']}}, 18:20:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:20:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:20:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:20:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:20:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:20:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:20:14 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:20:14 INFO - 'talos_options': ['--disable-e10s'], 18:20:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:20:14 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:20:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:20:14 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:20:14 INFO - 'talos_options': ['--disable-e10s'], 18:20:14 INFO - 'tests': ['damp', 'tps']}, 18:20:14 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:20:14 INFO - 'tests': ['damp', 'tps']}, 18:20:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:20:14 INFO - 'tests': ['dromaeo_dom']}, 18:20:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:20:14 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:20:14 INFO - 'tests': ['basic_compositor_video']}, 18:20:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:20:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:20:14 INFO - 'tests': ['a11yr', 18:20:14 INFO - 'ts_paint', 18:20:14 INFO - 'tpaint', 18:20:14 INFO - 'sessionrestore', 18:20:14 INFO - 'sessionrestore_no_auto_restore', 18:20:14 INFO - 'tabpaint']}, 18:20:14 INFO - 'other-e10s': {'tests': ['a11yr', 18:20:14 INFO - 'ts_paint', 18:20:14 INFO - 'tpaint', 18:20:14 INFO - 'sessionrestore', 18:20:14 INFO - 'sessionrestore_no_auto_restore', 18:20:14 INFO - 'tabpaint']}, 18:20:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:20:14 INFO - 'ts_paint', 18:20:14 INFO - 'tpaint', 18:20:14 INFO - 'sessionrestore', 18:20:14 INFO - 'sessionrestore_no_auto_restore', 18:20:14 INFO - 'tabpaint']}, 18:20:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:20:14 INFO - 'ts_paint', 18:20:14 INFO - 'tpaint', 18:20:14 INFO - 'sessionrestore', 18:20:14 INFO - 'sessionrestore_no_auto_restore', 18:20:14 INFO - 'tabpaint']}, 18:20:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:20:14 INFO - 'tests': ['a11yr', 18:20:14 INFO - 'ts_paint', 18:20:14 INFO - 'tpaint', 18:20:14 INFO - 'sessionrestore', 18:20:14 INFO - 'sessionrestore_no_auto_restore', 18:20:14 INFO - 'tabpaint']}, 18:20:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:20:14 INFO - 'tests': ['a11yr', 18:20:14 INFO - 'ts_paint', 18:20:14 INFO - 'tpaint', 18:20:14 INFO - 'sessionrestore', 18:20:14 INFO - 'sessionrestore_no_auto_restore', 18:20:14 INFO - 'tabpaint']}, 18:20:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:20:14 INFO - 'tests': ['tsvgx', 18:20:14 INFO - 'tsvgr_opacity', 18:20:14 INFO - 'tart', 18:20:14 INFO - 'tscrollx', 18:20:14 INFO - 'cart']}, 18:20:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:20:14 INFO - 'tsvgr_opacity', 18:20:14 INFO - 'tart', 18:20:14 INFO - 'tscrollx', 18:20:14 INFO - 'cart']}, 18:20:14 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:20:14 INFO - 'talos_options': ['--disable-e10s'], 18:20:14 INFO - 'tests': ['tp5o']}, 18:20:14 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:20:14 INFO - 'tests': ['tp5o']}, 18:20:14 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:20:14 INFO - 'talos_options': ['--disable-e10s', 18:20:14 INFO - '--xperf_path', 18:20:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:20:14 INFO - 'tests': ['tp5n']}, 18:20:14 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:20:14 INFO - 'talos_options': ['--xperf_path', 18:20:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:20:14 INFO - 'tests': ['tp5n']}}, 18:20:14 INFO - 'talos.zip': {'path': '', 18:20:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:20:14 INFO - Running post-action listener: _resource_record_post_action 18:20:14 INFO - [mozharness: 2016-09-01 01:20:14.725835Z] Finished populate-webroot step (success) 18:20:14 INFO - [mozharness: 2016-09-01 01:20:14.725963Z] Running create-virtualenv step. 18:20:14 INFO - Running pre-action listener: _resource_record_pre_action 18:20:14 INFO - Running main action method: create_virtualenv 18:20:14 INFO - Creating virtualenv /builds/slave/test/build/venv 18:20:14 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:20:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:20:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:20:14 INFO - Using real prefix '/tools/python27' 18:20:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:20:15 INFO - Installing distribute.............................................................................................................................................................................................done. 18:20:18 INFO - Installing pip.................done. 18:20:18 INFO - Return code: 0 18:20:18 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:20:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:20:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:20:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x1014d3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101568618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbc69c8dd80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101437d30>, '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 0x10119cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10142fb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6Ce2dIxkcZ/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9S2VLVFUD1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:20:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:20:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:20:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6Ce2dIxkcZ/Render', 18:20:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:20:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:20:18 INFO - 'HOME': '/Users/cltbld', 18:20:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:20:18 INFO - 'LOGNAME': 'cltbld', 18:20:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:18 INFO - 'MOZ_NO_REMOTE': '1', 18:20:18 INFO - 'NO_EM_RESTART': '1', 18:20:18 INFO - 'PAGER': '/bin/cat', 18:20:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:20:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:18 INFO - 'PWD': '/builds/slave/test', 18:20:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:20:18 INFO - 'SHELL': '/bin/bash', 18:20:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9S2VLVFUD1/Listeners', 18:20:18 INFO - 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', 18:20:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:20:18 INFO - 'USER': 'cltbld', 18:20:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:20:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:20:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:18 INFO - 'XPC_FLAGS': '0x0', 18:20:18 INFO - 'XPC_SERVICE_NAME': '0', 18:20:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:20:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:18 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 18:20:18 INFO - Cleaning up... 18:20:18 INFO - Return code: 0 18:20:18 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:20:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:20:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:20:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x1014d3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101568618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbc69c8dd80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101437d30>, '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 0x10119cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10142fb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6Ce2dIxkcZ/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9S2VLVFUD1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:20:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:20:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:20:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6Ce2dIxkcZ/Render', 18:20:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:20:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:20:18 INFO - 'HOME': '/Users/cltbld', 18:20:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:20:18 INFO - 'LOGNAME': 'cltbld', 18:20:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:18 INFO - 'MOZ_NO_REMOTE': '1', 18:20:18 INFO - 'NO_EM_RESTART': '1', 18:20:18 INFO - 'PAGER': '/bin/cat', 18:20:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:20:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:18 INFO - 'PWD': '/builds/slave/test', 18:20:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:20:18 INFO - 'SHELL': '/bin/bash', 18:20:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9S2VLVFUD1/Listeners', 18:20:18 INFO - 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', 18:20:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:20:18 INFO - 'USER': 'cltbld', 18:20:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:20:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:20:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:18 INFO - 'XPC_FLAGS': '0x0', 18:20:18 INFO - 'XPC_SERVICE_NAME': '0', 18:20:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:20:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:18 INFO - Downloading/unpacking psutil>=3.1.1 18:20:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:20:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:20:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:20:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:20:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:20:22 INFO - Installing collected packages: psutil 18:20:22 INFO - Running setup.py install for psutil 18:20:22 INFO - building 'psutil._psutil_osx' extension 18:20:22 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 18:20:22 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 18:20:22 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 18:20:23 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 18:20:23 INFO - building 'psutil._psutil_posix' extension 18:20:23 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 18:20:23 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:20:23 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:20:23 INFO - ^ 18:20:23 INFO - 1 warning generated. 18:20:23 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 18:20:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:20:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:20:23 INFO - Successfully installed psutil 18:20:23 INFO - Cleaning up... 18:20:23 INFO - Return code: 0 18:20:23 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:20:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:20:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:20:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x1014d3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101568618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbc69c8dd80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101437d30>, '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 0x10119cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10142fb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6Ce2dIxkcZ/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9S2VLVFUD1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:20:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:20:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:20:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6Ce2dIxkcZ/Render', 18:20:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:20:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:20:23 INFO - 'HOME': '/Users/cltbld', 18:20:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:20:23 INFO - 'LOGNAME': 'cltbld', 18:20:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:23 INFO - 'MOZ_NO_REMOTE': '1', 18:20:23 INFO - 'NO_EM_RESTART': '1', 18:20:23 INFO - 'PAGER': '/bin/cat', 18:20:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:20:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:23 INFO - 'PWD': '/builds/slave/test', 18:20:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:20:23 INFO - 'SHELL': '/bin/bash', 18:20:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9S2VLVFUD1/Listeners', 18:20:23 INFO - 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', 18:20:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:20:23 INFO - 'USER': 'cltbld', 18:20:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:20:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:20:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:23 INFO - 'XPC_FLAGS': '0x0', 18:20:23 INFO - 'XPC_SERVICE_NAME': '0', 18:20:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:20:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:23 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:20:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:20:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:20:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:26 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:20:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:20:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:20:26 INFO - Installing collected packages: mozsystemmonitor 18:20:26 INFO - Running setup.py install for mozsystemmonitor 18:20:26 INFO - Successfully installed mozsystemmonitor 18:20:26 INFO - Cleaning up... 18:20:26 INFO - Return code: 0 18:20:26 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:20:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:20:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:20:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x1014d3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101568618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbc69c8dd80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101437d30>, '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 0x10119cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10142fb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6Ce2dIxkcZ/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9S2VLVFUD1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:20:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:20:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:20:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6Ce2dIxkcZ/Render', 18:20:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:20:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:20:26 INFO - 'HOME': '/Users/cltbld', 18:20:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:20:26 INFO - 'LOGNAME': 'cltbld', 18:20:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:26 INFO - 'MOZ_NO_REMOTE': '1', 18:20:26 INFO - 'NO_EM_RESTART': '1', 18:20:26 INFO - 'PAGER': '/bin/cat', 18:20:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:20:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:26 INFO - 'PWD': '/builds/slave/test', 18:20:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:20:26 INFO - 'SHELL': '/bin/bash', 18:20:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9S2VLVFUD1/Listeners', 18:20:26 INFO - 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', 18:20:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:20:26 INFO - 'USER': 'cltbld', 18:20:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:20:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:20:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:26 INFO - 'XPC_FLAGS': '0x0', 18:20:26 INFO - 'XPC_SERVICE_NAME': '0', 18:20:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:20:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:26 INFO - Downloading/unpacking jsonschema==2.5.1 18:20:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:20:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:20:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:20:29 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:20:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:20:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:20:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:29 INFO - Downloading functools32-3.2.3-2.tar.gz 18:20:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:20:29 INFO - warning: no files found matching '*.txt' 18:20:29 INFO - no previously-included directories found matching 'build' 18:20:29 INFO - no previously-included directories found matching 'dist' 18:20:29 INFO - no previously-included directories found matching '.git*' 18:20:29 INFO - Installing collected packages: jsonschema, functools32 18:20:29 INFO - Running setup.py install for functools32 18:20:29 INFO - warning: no files found matching '*.txt' 18:20:29 INFO - no previously-included directories found matching 'build' 18:20:29 INFO - no previously-included directories found matching 'dist' 18:20:29 INFO - no previously-included directories found matching '.git*' 18:20:29 INFO - Successfully installed jsonschema functools32 18:20:29 INFO - Cleaning up... 18:20:29 INFO - Return code: 0 18:20:29 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:20:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:20:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:20:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x1014d3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101568618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbc69c8dd80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101437d30>, '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 0x10119cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10142fb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6Ce2dIxkcZ/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9S2VLVFUD1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:20:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:20:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:20:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6Ce2dIxkcZ/Render', 18:20:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:20:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:20:29 INFO - 'HOME': '/Users/cltbld', 18:20:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:20:29 INFO - 'LOGNAME': 'cltbld', 18:20:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:29 INFO - 'MOZ_NO_REMOTE': '1', 18:20:29 INFO - 'NO_EM_RESTART': '1', 18:20:29 INFO - 'PAGER': '/bin/cat', 18:20:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:20:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:29 INFO - 'PWD': '/builds/slave/test', 18:20:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:20:29 INFO - 'SHELL': '/bin/bash', 18:20:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9S2VLVFUD1/Listeners', 18:20:29 INFO - 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', 18:20:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:20:29 INFO - 'USER': 'cltbld', 18:20:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:20:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:20:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:29 INFO - 'XPC_FLAGS': '0x0', 18:20:29 INFO - 'XPC_SERVICE_NAME': '0', 18:20:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:20:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:30 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:20:30 INFO - Cleaning up... 18:20:30 INFO - Return code: 0 18:20:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:20:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:20:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:20:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x1014d3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101568618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbc69c8dd80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101437d30>, '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 0x10119cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10142fb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6Ce2dIxkcZ/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9S2VLVFUD1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:20:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:20:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:20:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6Ce2dIxkcZ/Render', 18:20:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:20:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:20:30 INFO - 'HOME': '/Users/cltbld', 18:20:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:20:30 INFO - 'LOGNAME': 'cltbld', 18:20:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:30 INFO - 'MOZ_NO_REMOTE': '1', 18:20:30 INFO - 'NO_EM_RESTART': '1', 18:20:30 INFO - 'PAGER': '/bin/cat', 18:20:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:20:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:30 INFO - 'PWD': '/builds/slave/test', 18:20:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:20:30 INFO - 'SHELL': '/bin/bash', 18:20:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9S2VLVFUD1/Listeners', 18:20:30 INFO - 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', 18:20:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:20:30 INFO - 'USER': 'cltbld', 18:20:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:20:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:20:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:30 INFO - 'XPC_FLAGS': '0x0', 18:20:30 INFO - 'XPC_SERVICE_NAME': '0', 18:20:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:20:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:30 INFO - Downloading/unpacking blobuploader==1.2.4 18:20:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:20:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:20:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:32 INFO - Downloading blobuploader-1.2.4.tar.gz 18:20:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:20:33 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:20:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:20:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:20:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:20:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:20:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:20:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:20:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:34 INFO - Downloading docopt-0.6.1.tar.gz 18:20:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:20:34 INFO - Installing collected packages: blobuploader, requests, docopt 18:20:34 INFO - Running setup.py install for blobuploader 18:20:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:20:34 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:20:34 INFO - Running setup.py install for requests 18:20:34 INFO - Running setup.py install for docopt 18:20:34 INFO - Successfully installed blobuploader requests docopt 18:20:34 INFO - Cleaning up... 18:20:35 INFO - Return code: 0 18:20:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:20:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:20:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:20:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x1014d3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101568618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbc69c8dd80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101437d30>, '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 0x10119cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10142fb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6Ce2dIxkcZ/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9S2VLVFUD1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:20:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:20:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:20:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6Ce2dIxkcZ/Render', 18:20:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:20:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:20:35 INFO - 'HOME': '/Users/cltbld', 18:20:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:20:35 INFO - 'LOGNAME': 'cltbld', 18:20:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:35 INFO - 'MOZ_NO_REMOTE': '1', 18:20:35 INFO - 'NO_EM_RESTART': '1', 18:20:35 INFO - 'PAGER': '/bin/cat', 18:20:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:20:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:35 INFO - 'PWD': '/builds/slave/test', 18:20:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:20:35 INFO - 'SHELL': '/bin/bash', 18:20:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9S2VLVFUD1/Listeners', 18:20:35 INFO - 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', 18:20:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:20:35 INFO - 'USER': 'cltbld', 18:20:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:20:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:20:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:35 INFO - 'XPC_FLAGS': '0x0', 18:20:35 INFO - 'XPC_SERVICE_NAME': '0', 18:20:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:20:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:20:35 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-qcZMbw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:20:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:20:35 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-LlmMRC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:20:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:20:35 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-OZp7tV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:20:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:20:35 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-aUiiMm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:20:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:20:35 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-uAOL4Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:20:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:20:35 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-mEzKTk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:20:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:20:35 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-brZZM9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:20:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:20:36 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-5B2niu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:20:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:20:36 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-3ZlPkA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:20:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:20:36 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-oqFgV3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:20:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:20:36 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-C1S7E5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:20:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:20:36 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-RXWoA3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:20:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:20:36 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-nXRFPc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:20:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:20:36 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-Waxb_H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:20:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:20:36 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-o9lvVm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:20:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:20:36 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-2kVS3N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:20:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:20:36 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-WK9MZs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:20:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:20:37 INFO - Running setup.py install for manifestparser 18:20:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:20:37 INFO - Running setup.py install for mozcrash 18:20:37 INFO - Running setup.py install for mozdebug 18:20:37 INFO - Running setup.py install for mozdevice 18:20:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:20:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:20:37 INFO - Running setup.py install for mozfile 18:20:37 INFO - Running setup.py install for mozhttpd 18:20:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:20:37 INFO - Running setup.py install for mozinfo 18:20:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:20:38 INFO - Running setup.py install for mozInstall 18:20:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:20:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:20:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:20:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:20:38 INFO - Running setup.py install for mozleak 18:20:38 INFO - Running setup.py install for mozlog 18:20:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:20:38 INFO - Running setup.py install for moznetwork 18:20:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:20:38 INFO - Running setup.py install for mozprocess 18:20:38 INFO - Running setup.py install for mozprofile 18:20:39 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:20:39 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:20:39 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:20:39 INFO - Running setup.py install for mozrunner 18:20:39 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:20:39 INFO - Running setup.py install for mozscreenshot 18:20:39 INFO - Running setup.py install for moztest 18:20:39 INFO - Running setup.py install for mozversion 18:20:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:20:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:20:39 INFO - Cleaning up... 18:20:39 INFO - Return code: 0 18:20:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:20:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:20: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')]} 18:20:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:20:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x1014d3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101568618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbc69c8dd80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101437d30>, '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 0x10119cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10142fb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6Ce2dIxkcZ/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9S2VLVFUD1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:20:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:20:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:20:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6Ce2dIxkcZ/Render', 18:20:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:20:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:20:40 INFO - 'HOME': '/Users/cltbld', 18:20:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:20:40 INFO - 'LOGNAME': 'cltbld', 18:20:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:40 INFO - 'MOZ_NO_REMOTE': '1', 18:20:40 INFO - 'NO_EM_RESTART': '1', 18:20:40 INFO - 'PAGER': '/bin/cat', 18:20:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:20:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:40 INFO - 'PWD': '/builds/slave/test', 18:20:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:20:40 INFO - 'SHELL': '/bin/bash', 18:20:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9S2VLVFUD1/Listeners', 18:20:40 INFO - 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', 18:20:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:20:40 INFO - 'USER': 'cltbld', 18:20:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:20:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:20:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:40 INFO - 'XPC_FLAGS': '0x0', 18:20:40 INFO - 'XPC_SERVICE_NAME': '0', 18:20:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:20:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:20:40 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-29UVIX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:20:40 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 18:20:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:20:40 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-ZaBLU7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:20:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:20:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:20:40 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-0w7AvZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:20:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:20:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:20:40 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-ydYZ8C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:20:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:20:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:20:40 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-RK74oC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:20:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 18:20:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:20:40 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-YaXk4T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:20:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 18:20:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:20:40 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-YgnQpV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:20:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 18:20:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:20:41 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-SajFJ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:20:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:20:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:20:41 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-A6MVTV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:20:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 18:20:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:20:41 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-LLbcX3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:20:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:20:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:20:41 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-KD2v2K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:20:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 18:20:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:20:41 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-ZlMlfT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:20:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 18:20:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:20:41 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-wovizT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:20:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 18:20:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:20:41 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-lkJmH0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:20:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 18:20:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:20:41 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-6YD7gq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:20:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 18:20:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:20:41 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-WRsL47-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:20:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 18:20:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:20:42 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-B_78f3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:20:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 18:20:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:20:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:20:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:20:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:20:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:20:42 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:20: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 18:20: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 18:20:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:20:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:20: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 18:20: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 18:20:45 INFO - Downloading blessings-1.6.tar.gz 18:20:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:20:45 INFO - Installing collected packages: blessings 18:20:45 INFO - Running setup.py install for blessings 18:20:45 INFO - Successfully installed blessings 18:20:45 INFO - Cleaning up... 18:20:45 INFO - Return code: 0 18:20:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:20:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:20:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:20:45 INFO - Reading from file tmpfile_stdout 18:20:45 INFO - Current package versions: 18:20:45 INFO - blessings == 1.6 18:20:45 INFO - blobuploader == 1.2.4 18:20:45 INFO - docopt == 0.6.1 18:20:45 INFO - functools32 == 3.2.3-2 18:20:45 INFO - jsonschema == 2.5.1 18:20:45 INFO - manifestparser == 1.1 18:20:45 INFO - mozInstall == 1.12 18:20:45 INFO - mozcrash == 0.17 18:20:45 INFO - mozdebug == 0.1 18:20:45 INFO - mozdevice == 0.48 18:20:45 INFO - mozfile == 1.2 18:20:45 INFO - mozhttpd == 0.7 18:20:45 INFO - mozinfo == 0.9 18:20:45 INFO - mozleak == 0.1 18:20:45 INFO - mozlog == 3.3 18:20:45 INFO - moznetwork == 0.27 18:20:45 INFO - mozprocess == 0.23 18:20:45 INFO - mozprofile == 0.28 18:20:45 INFO - mozrunner == 6.12 18:20:45 INFO - mozscreenshot == 0.1 18:20:45 INFO - mozsystemmonitor == 0.3 18:20:45 INFO - moztest == 0.7 18:20:45 INFO - mozversion == 1.4 18:20:45 INFO - psutil == 3.1.1 18:20:45 INFO - requests == 1.2.3 18:20:45 INFO - wsgiref == 0.1.2 18:20:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:20: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')]} 18:20:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:20: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')]} 18:20:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:20:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x1014d3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101568618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbc69c8dd80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101437d30>, '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 0x10119cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10142fb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6Ce2dIxkcZ/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9S2VLVFUD1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:20:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 18:20:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:20:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6Ce2dIxkcZ/Render', 18:20:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:20:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:20:45 INFO - 'HOME': '/Users/cltbld', 18:20:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:20:45 INFO - 'LOGNAME': 'cltbld', 18:20:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:45 INFO - 'MOZ_NO_REMOTE': '1', 18:20:45 INFO - 'NO_EM_RESTART': '1', 18:20:45 INFO - 'PAGER': '/bin/cat', 18:20:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:20:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:45 INFO - 'PWD': '/builds/slave/test', 18:20:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:20:45 INFO - 'SHELL': '/bin/bash', 18:20:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9S2VLVFUD1/Listeners', 18:20:45 INFO - 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', 18:20:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:20:45 INFO - 'USER': 'cltbld', 18:20:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:20:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:20:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:45 INFO - 'XPC_FLAGS': '0x0', 18:20:45 INFO - 'XPC_SERVICE_NAME': '0', 18:20:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:20:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:20:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 18:20:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 18:20:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:20:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 18:20:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 18:20:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 18:20:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 18:20:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 18:20:45 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:20:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:20:45 INFO - Cleaning up... 18:20:45 INFO - Return code: 0 18:20:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:20: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')]} 18:20:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:20: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')]} 18:20:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:20:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x1014d3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101568618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbc69c8dd80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101437d30>, '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 0x10119cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10142fb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6Ce2dIxkcZ/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9S2VLVFUD1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:20:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:20:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:20:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6Ce2dIxkcZ/Render', 18:20:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:20:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:20:45 INFO - 'HOME': '/Users/cltbld', 18:20:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:20:45 INFO - 'LOGNAME': 'cltbld', 18:20:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:45 INFO - 'MOZ_NO_REMOTE': '1', 18:20:45 INFO - 'NO_EM_RESTART': '1', 18:20:45 INFO - 'PAGER': '/bin/cat', 18:20:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:20:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:45 INFO - 'PWD': '/builds/slave/test', 18:20:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:20:45 INFO - 'SHELL': '/bin/bash', 18:20:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9S2VLVFUD1/Listeners', 18:20:45 INFO - 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', 18:20:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:20:45 INFO - 'USER': 'cltbld', 18:20:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:20:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:20:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:45 INFO - 'XPC_FLAGS': '0x0', 18:20:45 INFO - 'XPC_SERVICE_NAME': '0', 18:20:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:20:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:46 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:20:46 INFO - Cleaning up... 18:20:46 INFO - Return code: 0 18:20:46 INFO - Running post-action listener: _resource_record_post_action 18:20:46 INFO - Running post-action listener: _start_resource_monitoring 18:20:46 INFO - Starting resource monitoring. 18:20:46 INFO - [mozharness: 2016-09-01 01:20:46.260507Z] Finished create-virtualenv step (success) 18:20:46 INFO - [mozharness: 2016-09-01 01:20:46.261518Z] Running install step. 18:20:46 INFO - Running pre-action listener: _resource_record_pre_action 18:20:46 INFO - Running main action method: install 18:20:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:20:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:20:46 INFO - Reading from file tmpfile_stdout 18:20:46 INFO - Detecting whether we're running mozinstall >=1.0... 18:20:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:20:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:20:46 INFO - Reading from file tmpfile_stdout 18:20:46 INFO - Output received: 18:20:46 INFO - Usage: mozinstall [options] installer 18:20:46 INFO - Options: 18:20:46 INFO - -h, --help show this help message and exit 18:20:46 INFO - -d DEST, --destination=DEST 18:20:46 INFO - Directory to install application into. [default: 18:20:46 INFO - "/builds/slave/test"] 18:20:46 INFO - --app=APP Application being installed. [default: firefox] 18:20:46 INFO - mkdir: /builds/slave/test/build/application 18:20:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 18:20:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 18:21:20 INFO - Reading from file tmpfile_stdout 18:21:20 INFO - Output received: 18:21:20 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:21:20 INFO - Running post-action listener: _resource_record_post_action 18:21:20 INFO - [mozharness: 2016-09-01 01:21:20.572456Z] Finished install step (success) 18:21:20 INFO - [mozharness: 2016-09-01 01:21:20.572597Z] Running run-tests step. 18:21:20 INFO - Running pre-action listener: _resource_record_pre_action 18:21:20 INFO - Running main action method: run_tests 18:21:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:21:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:21:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:21:20 INFO - 2016-08-31 18:21:20.587 screenresolution[1632:7403] starting screenresolution argv=screenresolution get 18:21:20 INFO - 2016-08-31 18:21:20.589 screenresolution[1632:7403] Display 0: 1600x1200x32@60 18:21:20 INFO - 2016-08-31 18:21:20.595 screenresolution[1633:7405] starting screenresolution argv=screenresolution list 18:21:20 INFO - Available Modes on Display 0 18:21:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:21:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:21:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:21:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:21:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:21:20 INFO - Intel Iris: 18:21:20 INFO - Chipset Model: Intel Iris 18:21:20 INFO - Type: GPU 18:21:20 INFO - Bus: Built-In 18:21:20 INFO - VRAM (Dynamic, Max): 1536 MB 18:21:20 INFO - Vendor: Intel (0x8086) 18:21:20 INFO - Device ID: 0x0a2e 18:21:20 INFO - Revision ID: 0x0009 18:21:20 INFO - Displays: 18:21:20 INFO - Display: 18:21:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:21:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:21:20 INFO - Display Serial Number: 1600x1200 60 18:21:20 INFO - Main Display: Yes 18:21:20 INFO - Mirror: Off 18:21:20 INFO - Online: Yes 18:21:20 INFO - Rotation: Supported 18:21:20 INFO - Return code: 0 18:21:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:21:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:21:20 INFO - Python 2.7.3 18:21:20 INFO - Return code: 0 18:21:20 INFO - grabbing minidump binary from tooltool 18:21: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')]} 18:21:20 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101437d30>, '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 0x10119cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10142fb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:21:20 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 18:21:20 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 18:21:20 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 18:21:20 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:21:20 INFO - Return code: 0 18:21:20 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:21:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:21:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:21:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:21:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:21:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SqFZLK2kRYqCHNmgJFZ1YA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0288', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 18:21:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/SqFZLK2kRYqCHNmgJFZ1YA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0288 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 18:21:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6Ce2dIxkcZ/Render', 18:21:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:21:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:21:20 INFO - 'HOME': '/Users/cltbld', 18:21:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:21:20 INFO - 'LOGNAME': 'cltbld', 18:21:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:21:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:21:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:21:20 INFO - 'MOZ_NO_REMOTE': '1', 18:21:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:21:20 INFO - 'NO_EM_RESTART': '1', 18:21:20 INFO - 'PAGER': '/bin/cat', 18:21:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:21:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:21:20 INFO - 'PWD': '/builds/slave/test', 18:21:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:21:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:21:20 INFO - 'SHELL': '/bin/bash', 18:21:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9S2VLVFUD1/Listeners', 18:21:20 INFO - 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', 18:21:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:21:20 INFO - 'USER': 'cltbld', 18:21:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:21:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:21:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:21:20 INFO - 'XPC_FLAGS': '0x0', 18:21:20 INFO - 'XPC_SERVICE_NAME': '0', 18:21:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:21:20 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SqFZLK2kRYqCHNmgJFZ1YA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0288', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 18:21:21 INFO - mozversion application_buildid: 20160831162521 18:21:21 INFO - mozversion application_changeset: adcf904e2d2b25bf38ded04a90862e2631ecabf4 18:21:21 INFO - mozversion application_display_name: Nightly 18:21:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:21:21 INFO - mozversion application_name: Firefox 18:21:21 INFO - mozversion application_remotingname: firefox 18:21:21 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 18:21:21 INFO - mozversion application_vendor: Mozilla 18:21:21 INFO - mozversion application_version: 51.0a1 18:21:21 INFO - mozversion platform_buildid: 20160831162521 18:21:21 INFO - mozversion platform_changeset: adcf904e2d2b25bf38ded04a90862e2631ecabf4 18:21:21 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 18:21:21 INFO - mozversion platform_version: 51.0a1 18:21:21 INFO - using testdate: 1472692881 18:21:21 INFO - actual date: 1472692881 18:21:21 INFO - starting webserver on 'localhost:49271' 18:21:21 INFO - SUITE-START | Running 2 tests 18:21:21 INFO - TEST-START | dromaeo_css 18:21:21 INFO - operating with platform_type : mac_ 18:21:21 INFO - Initialising browser for dromaeo_css test... 18:21:21 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/tmpo27u9H/profile http://localhost:49271/getInfo.html) 18:21:23 INFO - PROCESS | 1643 | __metrics Screen width/height:1600/1200 18:21:23 INFO - PROCESS | 1643 | colorDepth:24 18:21:23 INFO - PROCESS | 1643 | Browser inner width/height: 1024/689 18:21:23 INFO - PROCESS | 1643 | __metrics 18:21:23 INFO - PROCESS | 1643 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:21:23 INFO - PROCESS | 1643 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:21:24 INFO - TEST-INFO | 1643: exit 0 18:21:24 INFO - Browser initialized. 18:21:24 INFO - Running cycle 1/1 for dromaeo_css test... 18:21:24 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/tmpo27u9H/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:21:36 INFO - PROCESS | 1645 | RSS: Main: 234373120 18:21:36 INFO - PROCESS | 1645 | 18:22:27 INFO - PROCESS | 1645 | 1472692947880 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] 18:24:39 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 18:24:40 INFO - PROCESS | 1645 | RSS: Main: 375050240 18:24:40 INFO - PROCESS | 1645 | 18:27:44 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 18:27:44 INFO - PROCESS | 1645 | RSS: Main: 371109888 18:27:44 INFO - PROCESS | 1645 | 18:30:47 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 18:30:47 INFO - PROCESS | 1645 | RSS: Main: 358830080 18:30:47 INFO - PROCESS | 1645 | 18:33:50 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 18:33:50 INFO - PROCESS | 1645 | RSS: Main: 342249472 18:33:50 INFO - PROCESS | 1645 | 18:36:54 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 18:36:54 INFO - PROCESS | 1645 | RSS: Main: 326950912 18:36:54 INFO - PROCESS | 1645 | 18:39:58 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 18:39:58 INFO - PROCESS | 1645 | RSS: Main: 437116928 18:39:58 INFO - PROCESS | 1645 | 18:39:58 INFO - PROCESS | 1645 | __start_tp_report 18:39:58 INFO - PROCESS | 1645 | _x_x_mozilla_page_load 18:39:58 INFO - PROCESS | 1645 | _x_x_mozilla_page_load_details 18:39:58 INFO - PROCESS | 1645 | |i|pagename|runs| 18:39:58 INFO - PROCESS | 1645 | |0;dojo.html;3345.2305969626987;3350.2629421527263;3359.6304406515296;3381.9154521136984;3435.4846772984065;5536.390996990338;5583.190437386582;5627.380988091307;5678.914816277759;5694.886102277952;5663.292088488939;5691.544676425887;5696.11710184921;5697.145428185774;5713.028785106531;5148.742562871851;5185.092608793446;5201.271821944912;5206.010857936999;5266.183741520071;5171.405288391846;5223.085959957011;5238.13570760824;5240.554552863011;5247.606429517782;5193.948060519384;5198.1423065194385;5214;5214.869628259305;5260.8158714445;5243.790248390079;5282.154855223146;5288.286081379003;5297.894042119136;5323.653962492425;5045.268436076748;5100.719460429675;5140.588752899759;5187.19598462239;5203.765830537635;5585.1063829787445;5679.09134538476;5708.257926469532;5724.856878578027;5725.570582206351;6185.474234690057;6185.690715464254;6201.379862013808;6202.689865506752;6218.751249949996;6115.816525504242;6158.076288556752;6202.62784232943;6284.622922624657;6312.337204593543;5671.404502527175;5700.8859822803315;5704.71476426177;5753.913691294634;5756.28046494184;6253.062040693932;6275.811725648238;6293.590916590407;6293.905591416041;6317.652529110851;5762.88474230513;6032.4570788629235;6041.78853740117;6048.9697551512845;6058.212432383762;6184.257889053252;6212.06818977148;6233.283172435137;6261.5616906816085;6269.937300626935;6168.9691551542855;6182.1345011698395;6184.845378865564;6194.876102477925;6221.564490485713;5845.649261044351;5880.411958804086;6216.285127210338;6230.719617617218;6233.3143354231;4834.984653222792;4870;4880.731559764176;4904.877378065506;4931.211006238985;4366.366876802846;4371.475422949203;4376.737395756265;4378.058717375716;4435.6229720473475;5576.247206627135;5611.114973235517;5638.069718496402;5673.489385955201;5704.11586204139;6145.446909778052;6163.3528479509905;6193.194884664965;6206;6237.09562113491;5696.458836410535;5699.344575373802;5712.057510510719;5727.226824378656;5774.740136693859;5356.571474282048;5366.838994830161;5369.4630536946;5377.5429088527135;5383.569314454834;6239.251289845066;6254.562180647311;6268.655223962634;6292.905606415999;6350.96824515893;6234.9688251558455;6248.125262463168;6256.06159076124;6256.968715156395;6298.937010629835;6286.277078136073;6306.84232894163;6307.621542707452;6318.462930650946;6348.492120630246;5957.880842383215;6094.51243900478;6120.693965301806;6124.289394097845;6163.506919446523;6045.486133678686;6230.221222347207;6244.781432649836;6247.5001999840815;6250.874982500233;6013.188219590299;6040.469647764176;6055.182550355646;6069.969650151897;6113.388661133851;5882.323532793613;5992.340842507408;6020.969895150672;6033.034714445667;6117.96941015292;5843.474087332161;5863.794767183128;5872.265966754156;5887.028640274307;5896.056630939029;5974.2233509643465;6029.306629737636;6060.939335616337;6063.605865618721;6064.3026052002815;4887.875788568578;5739.024365857731;5812;5883.617564858442;5909.852253693692 18:39:58 INFO - PROCESS | 1645 | |1;ext.html;477.3317355702019;482.31992890025066;488.777606189184;492.76593618031416;533.3999250842802;30822.38355232894;39802.2039559209;41041.38437923433;41514.584854151486;42851;42013.57986420143;42408.15183696324;42478.725638230826;42529.57470425295;42586.57413425865;8639.092895246004;9546.379485333431;9727.56225969833;9742.22062235023;9742.707718768414;7224.169220539627;11661.59184428545;11944.820827687592;11960.222585531956;12214.389280535983;11927.880721192807;12006.219595726294;12006.879931200707;12007.519699212022;12029.338386388745;9870.358426702312;11938.925496705338;12015.519379224821;12038.518459261619;12046.69883252917;11766.647000589996;11849.881501184964;11857.407129643483;11878.940605297004;11894.702632434171;39830.40254396186;40061.99845003869;40139.59860401388;40178.79463616067;40244.993875153064;3211.3898359311656;3322.4850148227065;3333.250018745787;3339.215284408162;3428.3486137633768;30175.32331770257;31113.533297000562;31351;31366.52950205749;31660.683393166004;3156.4949608062598;3238.854251558662;3275.6233033201247;3294.917627059319;3355.983220083884;489447.1055289437;581042;592491;595108;596683;59530.70234648799;60625.69687151536;60636.696816515636;60717.69641151766;60851.695741521005;33749.48127334276;34370.312593747985;34409.483857742154;63094.36905630977;63403.682981584796;16273.267702953395;16438;16457;16473.423430180126;16474.917625412036;11322.830157547643;11523.654290371302;12425.943794777364;12600.495980160875;12721.363931803373;17974.37089701854;18311;18363.72454413185;18416.63166736658;18499.075046247635;6526.804195874131;6618.768343107968;7950.681972721142;8051.2159285135895;8058.556779377191;867.4968518259394;915.1534830281962;945.8581212818133;946.2051876423837;1012.2054187462807;25863.741362586134;28806.85596572004;29310.97411590584;29921.251968700955;30064.098077057723;1032.9431881246603;1058.2856571813995;1059.7562560611102;1077.06833588945;1090.547422819521;12314.81527777084;12502.81245781314;12750.68123296925;15226.121878819758;15303.61740956485;541285;546354;546595;547412;547467;53881.1917821233;54442;54530.454695452536;54535.63660908509;54553.908921822134;1243.5461056714234;1347.6630842289624;1367.7948307753718;1392.5891861900966;1393.6097892590092;30127.69353551494;30804.53793193149;30826.53760193554;31869;31907.521387178745;47520.7623961878;47529.76235118802;47695.523044770496;47783.761081194374;48050.759746201045;518407.4079629729;521205;521891.78108218434;523182;523350;26059.73940260573;26392.736072639796;26960;26971.325716857235;27831.721682783722;37238;46106.30840537462;46532.7673361631;46571.76714116408;47025.529744702115;37708;37734.43398348972;38734.612653874225;38962.610373897034;39768.403473947335;30082.398352033273;32618.51072233871;34269.314613708084;37678.24643507169;39084.2183156341;36445.27109457849;36913;37173.628263717015;37414.8129259352;37483.81258093692;32327.838360808044;33735.444249481305;33886;33948.151296217795;34044.489332659534;24658.753412465645;24753.133640322503;25003.12489062874;25353;25424.237272881102 18:39:58 INFO - PROCESS | 1645 | |2;jquery.html;347.3573888306631;364.68272602835526;371.89215127612977;378.9602091780362;400.0937875711514;15565.844341556582;16546.91726541367;20963.790362096373;21041.368758937257;21152.682709759345;20554;21105.68341474879;21559.676604850938;21610.35168944934;21797.67303490445;5984.611000284989;6101.511879049665;6103.938960610403;6162.291336496315;6204.5967012144065;6003.219581454405;6096.420840020191;6165;6190.535709821759;6198.721057552421;4190.245755763961;4344.348347747848;6722.865542689144;6736.629485378302;6809.353111454404;6575.506836987245;6683.565568238049;6738.629375384352;6740.797776066725;6752.527323087385;5719.542436605103;5846.824595262149;5850.12248162779;5853.443922827325;5856.472917437451;25002.874985624956;26417.603735943976;26420.73579264202;26517.93928242868;26742.73257267422;2696.8651567421552;2713.2674177972035;2719.986400068007;3070.078976307099;3216.9356612867846;15602.531924042298;15855.603609909844;16206.75689864653;16260.430884918971;16462.917685411616;1645.1617692913144;1881.3321270948752;1885.764279465067;1932.671445854208;1935.2839449403812;96827.51586242094;120101.39949300286;122313.77686223113;122690;122817.77182228153;9530.142287194185;9994.800103997879;11043.171762117874;11268.830967535494;11359;14035.017548771692;14069.085509441853;14087.788683169761;14106.435742570182;14133.505327313494;8358.498490090615;8599.742007739778;8661.263792577702;8812;8828;7221.819454513679;8660.826783464296;8688.435251708619;8697.565121744014;8894.42186257891;6630.30381809903;6741.359570840762;6751.278424001848;6761.053452516651;6786.8642627148165;1624.6831867785904;1835.4034938644993;1840.4142180236788;1841.3392647624323;1849.3342396737164;864.3085531574715;970.1559643110539;978.1490103609763;981.6417007792104;992.2905122837207;16194.75707864383;21810.781892180876;21838.23566175176;22145.778542214695;22299.996500157533;911.7600063913205;944.8866136063716;959.2949182350967;961.7980224152866;1007.9092881640687;10042.497875106215;11868.525258989717;12007.579734709243;12025.278483291031;12062.035037197003;543885;554220;561503;570620;574449.1277543586;68891.27771805745;69312;96316;103570.48214759029;120458.3977080109;1001.6944831826363;1021.99489002556;1046.9528871200814;1048.3343077146067;1049.0768124050787;15098.773518397013;19253.614927701088;19253.711194332376;19350.709739354203;19713.802861971195;39096.2180756389;40142.799286003385;44119;47440;51146.48853511417;72771.63614181895;74340.62829685817;74545.62727186547;74806;76613.23386766062;7149.678264478007;7887.250711182429;7975.601219938864;8306.086330503518;8479.236868681848;12772.808407874076;13364.532241371895;13372.197668139943;13578.728425431238;13656.112352697042;11398.259113157617;11442.256253343507;11698.415079246173;11751.412429378326;11774.882251177378;11107.722611899735;11324.263922844988;11354.829677555008;11445.427728613702;11748.823767643322;10967.53230523126;11361.431928403712;11621.360825154698;11623.302601843916;11809.704757381134;6001.969990150022;6625.768098116543;8015.438919275594;8386.454880432751;8495.787605309917;8321.08468068524;8781.604827782892;9877;10853.294535855144;11525.82711259315 18:39:58 INFO - PROCESS | 1645 | |3;mootools.html;1367.2001878900844;1379.1587131849565;1551.7827504149418;1749.3702267183812;1942.0192802634658;7845.411594130442;8659.437136586117;8928.598213080402;8953;9030.096990300966;8788.736337909857;8858.291336693064;8866.512341821197;8889.199972002518;8927.598258078386;2394.090245706629;2421.309926670899;2422.055398394619;2422.672939153218;2426.9271921842374;2373.703287089114;2396.400899775056;2421.382547450398;2422.406510404953;2422.4670572474115;2385.5667219436164;2407.7712617301418;2416.2630397728685;2425.405775584984;2426.7330593634774;2350.506393657317;2370.12305446984;2380.500094980048;2386.820988425875;2396;2345.7302410222874;2369.1234243329905;2371.8814059296965;2372.1341710275797;2376.3821406434276;8756.474611523265;8766.474011559327;8951.32865035125;8978.685745998924;8981.326400519987;5283.207518872162;5467.726613669301;5472;5480.972595137038;5484.396716361197;3138.0272115644225;3682.3739964206147;3706.22169344438;3764.3788774852114;3790.2608991246716;1107.3577325151393;1115.8605174353206;1116.4529380603526;1126.8873112688748;1131.8585176852894;11915.98714109293;11960.46177921987;12019.459124339426;12020;12045.698857528545;4457.086297309044;4516;4704.976859950254;4705.529447055335;4721.386219791405;7804.726834560764;9297.116773906468;9320.580573874191;9328.486933218737;9349.111834375723;5296.682199068068;5298.97350513245;5303.628745987744;5307.4692530746615;5312.92030619541;4420.270655341897;4582.977085114553;4788.736619485892;4962.007598480318;4986.750662466935;3225.483922572377;3803.8288277027045;3849.7488316296754;3858.5369755629504;3887.475190849199;1174.1018121137338;1184.8341232227494;1188.6552899659002;1200.5137919142762;1200.9279443233434;792.4730054513799;853.8463076646264;908.5094049213483;922.6355589542228;950.6577632052456;5880;5916.792912248136;5933.58464907461;6072.544559158081;6079.787207447718;406.6685651194267;940.05994005994;940.9388389754644;973.0609961387295;1015.4262841494532;3902.317094508506;3964.207158568298;3985.1830374773103;4043.1509383029893;4105.4252404663375;10538.262321637565;10987.340759554452;11006.669799906016;11016.394098324668;11019.559217631366;10955.616553420592;10968.122550195965;10968.287061340987;10971.396573188551;10977.28647637901;7947.364210863233;8336.541490217975;8337.458065225741;8337.791555211046;8445.282151017109;5886.293644762656;5963.165156878127;6021.849453763516;6163.537734669828;6239.313675495782;10052.095311422006;10240.053884593835;10341.276110672181;10343.534540945526;10352;10085.89914100879;10112.544935477774;10166.186705063725;10404.606940403599;10538.78922421532;7066.681999309941;7311.4516411268305;7421.443391745749;7581.810454738676;7714.11287701899;8433.578321084044;8595.44129631572;8602.741917742478;8616.741497755078;8632.611532480927;7396.223396543501;7412.851742965003;7562.584057876869;7670.8082297943;7675.347595454449;7394;7521.323080922743;7578.469507134448;7580.886286705814;7619.123800762762;6963.09479767627;7085.964570177116;7086.610236437045;7087.574745515286;7090.503664743418;4975.452700203047;5075.467075956972;5090.400559384647;5104.285400043923;5250.684958902325;4257.467816522934;5073.797048118182;5090;5139.6145289102715;5259.42146363892 18:39:58 INFO - PROCESS | 1645 | |4;prototype.html;297.9240293725101;298.1131134873751;308.2386505331831;312.56241262232885;314.2740269976355;5128.794848206067;5300.178472336785;5335.733213339332;5357.464253574645;5423.186522021694;4204.5585213552595;5067.315912351832;5156.355455568054;5202.557782588475;5309.840704778853;3563.6258192889763;3848.26882892251;4042.939355909664;4083.3670781028986;4113.950942509656;3688.2807852468645;3823.833730712132;3845.3270677631444;3947.5657677655436;4136.27615167346;3302.6862448067395;3779.2441511697634;4026.617471340218;4068.5214141908687;4070.9796451017705;4014.3376342903384;4019.5176578810438;4041.2523683118425;4042.3128068228325;4053.3312003519386;3247.675232476757;3404.9148771280916;3431.673990970854;3542.6280240574624;3561.51919490868;4787.366948178488;4836.879078023042;4901.607871370282;4910.312556242093;5206.843794686166;3728.440733889911;3818.0673905876993;4526.275795872678;4535.866033491627;4561.292999585036;5526.392096869341;5543.867714741158;5549.5837812164245;5570.86072848178;5619.522340601054;3649.2154186849953;3782.829772660237;3784.8107594620433;3805.4101614249557;3814.351560234739;5810.891173222306;5812.709364531756;5817.0983497557945;5822.244652199308;5838.7080645967535;3789.223209242154;3819.6058438669684;3825.1967086911504;3826.1391186983265;3843.1929294847837;3245.7241134503834;3680.337539242962;3893.279743247508;3902.9609703902597;3903.980480097581;3828.73198876076;4060.127072679329;4508.932366014513;4523.185826551186;4563.53966730643;5161.870953226199;5498.697571633598;5631.662100273997;5647.632903861236;5665.065264231355;1252.348778635123;1298.3121941476043;1299.311364976545;1301.1737546657941;1318.2947123289186;867.8437881181447;881.6032785246665;894.7673604862782;896.5830888636815;898.5192921786812;4504.391907092566;5063.291139240509;5178.404483484447;5179.456157103526;5189.377274727057;4239.215745087169;4350;4978.477259887732;5436.912617476521;5499.900019996016;4815.167578403585;4944.184209605375;5070.6957582545165;5129.615278854101;5158.664686795421;1971.6943873699313;2097.748270207585;2449.8530088194407;2494.3764058985253;2535.5151951088037;4028.697847661437;4311.590398912161;4522.321651752263;4619.976900115478;4924.507549245047;4460.353248778909;4835.516448355207;4849.951500485021;4867.683600565952;4908.318760929303;4326.870193894189;4388.4733831940375;4516.638668906479;4582.747948862844;4953.678010929278;3780.3951367781024;3898.4347269645177;4256.063316311735;4266.935995960125;4774.76126193682;4696.393501606641;4711.246200607886;4725.637181409295;4744.952550474451;4745.169595320736;4204.738578426399;4430.556944305544;4782.832600858953;4914.459409464885;4982.775775090057;2941.4558306713325;2977.761779057627;3191.632962209376;3236.8543415545887;3338.732901367933;4087.427760113646;4596.701214421052;4739.559127049503;4808.869915569909;5426.430224826337;5354.839354819362;5488.862778430571;5529.253550770594;5614.887702246014;5617.410171931889;5251.350311533448;5437.26596909428;5573.6098473106495;5608.775648973995;5629.493345598916;4505.189065968158;5113.4375218726655;5135.305349234669;5449.782008719607;5493.395726470005;4646.535346465326;5456.672599644034;5521.82495065331;5532.3637781654015;5632.859178520406;4554.27131658933;4678.625709943264;5454.318210223722;5480.547654871542;5481.561475081904 18:39:58 INFO - PROCESS | 1645 | |5;yui.html;693.549193024534;738.287552511826;751.0911796725961;799.6041959230182;855.9015713192977;14010.719785604282;17873;17883.19525621347;17922.103894805256;17937.192826322847;16022.878398512108;16129.112898790561;16160.030398176117;16164.35342586298;16202.918985405056;663.9834004149886;840.3823189955378;868.7697760093548;871.2202578692066;872.0451106038909;680.9387155156035;694.9374556289932;848.0078308379211;870.7257213977576;874.3005595523588;892.451142547332;893.8301722672704;894.8255090257402;895.1451363947415;896.224765577777;697.8708938846279;888.9911100889005;889.3107841422884;889.7997950461126;890.7505898348472;641.3490307338055;658.9505787065989;662.066486254379;665.1187176990459;668.3216535216759;15149.091054536762;15231.923840380727;15240.923795381063;15241.466548670744;15244.46644367453;13245.86754132456;13594.252316122609;13775.104618199884;13820.170789752647;13989.30053497321;11242.437878106062;11565.653030409101;11984.161108722394;12065.939670301592;12280.692982675415;3779.8677046303246;3793.639604237593;3821.159344944108;3942.42834788954;3965.1474932889278;112018;113316;113541.43229283883;113590.43204784006;113941.99999999917;10425.582976680847;10631.569017818565;10832.458377081115;11071.889281107247;11085.113190944705;22492.662610060863;22802.543949120925;22807;22823.88588057049;22826.31521054371;6512.706928188243;7853.057633084067;8004.796733464121;8068.3948703847445;8116.310113640288;4283.971846756818;4458;4478.731276123443;4528.88677783058;4655.650826188049;3592.3713350163616;3705.444183372462;3707.8331475083687;3710.109573702292;3714.405695088773;608.8978948103946;637.1812221295613;648.7015972652632;668.8762578922915;682.5222344358968;582.7144699097496;658.7595527632379;668.9564279723646;679.6907407129744;680.7304377336233;8856.468611883307;9196.954015230014;9294.488803115893;9445.811083778286;9455.716328510156;615.5473083522883;664.6743095882982;665.7769647168157;667.0927538547571;669.8158006548239;4139.503259608866;4473.261911784584;4508.504064552896;4535.353032039477;4655.557722016403;92305.53847230856;92561.53719231361;92666;95934;98065;105584;109722.45138774415;111983;115744.26383604253;124221;73754.63122684353;74099;98146;104642;108246;8665.870011949952;8955.462672239686;9869.407835529892;9890.32802342327;9997;53592.196117057494;57313.426865730806;58618.12072818826;61635;62819.6859015702;69561.95657065249;85946.57026714826;89703;90688;93240.53379733057;10879.619213327496;12326.32205228721;12469.438875250817;13057.804132938203;13497.460101595827;13184.340782961006;14476.765776248192;14789.408423662933;15001.549953501413;15419.61450963735;11048.558057677603;13179;13215.339233038118;13240.404181812035;13484.00178032715;13399;13615.046946713635;13695.383707732977;13777.380017899419;14117.576472705836;11632.418379080842;11839.638441579329;12493.25040497573;13999.580012599637;14398.856011439751;8121.862939188401;10997.780044399227;11450.427478626201;11463.426828658701;11572.132090093108;9235.492047937429;10048.598056077675;11326.943365283121;11490.253133546294;11523.078153747512 18:39:58 INFO - PROCESS | 1645 | __end_tp_report 18:39:58 INFO - PROCESS | 1645 | __start_cc_report 18:39:58 INFO - PROCESS | 1645 | _x_x_mozilla_cycle_collect,1277 18:39:58 INFO - PROCESS | 1645 | __end_cc_report 18:39:58 INFO - PROCESS | 1645 | __startTimestamp1472693998566__endTimestamp 18:39:58 INFO - PROCESS | 1645 | 18:39:58 INFO - PROCESS | 1645 | ------- Summary: start ------- 18:39:58 INFO - PROCESS | 1645 | Number of tests: 6 18:39:58 INFO - PROCESS | 1645 | 18:39:58 INFO - PROCESS | 1645 | [#0] dojo.html Cycles:165 Average:5706.61 Median:5872.27 stddev:629.90 (10.7%) stddev-sans-first:603.98 18:39:58 INFO - PROCESS | 1645 | Values: 3345.2 3350.3 3359.6 3381.9 3435.5 5536.4 5583.2 5627.4 5678.9 5694.9 5663.3 5691.5 5696.1 5697.1 5713.0 5148.7 5185.1 5201.3 5206.0 5266.2 5171.4 5223.1 5238.1 5240.6 5247.6 5193.9 5198.1 5214.0 5214.9 5260.8 5243.8 5282.2 5288.3 5297.9 5323.7 5045.3 5100.7 5140.6 5187.2 5203.8 5585.1 5679.1 5708.3 5724.9 5725.6 6185.5 6185.7 6201.4 6202.7 6218.8 6115.8 6158.1 6202.6 6284.6 6312.3 5671.4 5700.9 5704.7 5753.9 5756.3 6253.1 6275.8 6293.6 6293.9 6317.7 5762.9 6032.5 6041.8 6049.0 6058.2 6184.3 6212.1 6233.3 6261.6 6269.9 6169.0 6182.1 6184.8 6194.9 6221.6 5845.6 5880.4 6216.3 6230.7 6233.3 4835.0 4870.0 4880.7 4904.9 4931.2 4366.4 4371.5 4376.7 4378.1 4435.6 5576.2 5611.1 5638.1 5673.5 5704.1 6145.4 6163.4 6193.2 6206.0 6237.1 5696.5 5699.3 5712.1 5727.2 5774.7 5356.6 5366.8 5369.5 5377.5 5383.6 6239.3 6254.6 6268.7 6292.9 6351.0 6235.0 6248.1 6256.1 6257.0 6298.9 6286.3 6306.8 6307.6 6318.5 6348.5 5957.9 6094.5 6120.7 6124.3 6163.5 6045.5 6230.2 6244.8 6247.5 6250.9 6013.2 6040.5 6055.2 6070.0 6113.4 5882.3 5992.3 6021.0 6033.0 6118.0 5843.5 5863.8 5872.3 5887.0 5896.1 5974.2 6029.3 6060.9 6063.6 6064.3 4887.9 5739.0 5812.0 5883.6 5909.9 18:39:58 INFO - PROCESS | 1645 | 18:39:58 INFO - PROCESS | 1645 | [#1] ext.html Cycles:180 Average:67620.57 Median:33996.32 stddev:145914.82 (429.2%) stddev-sans-first:146237.06 18:39:58 INFO - PROCESS | 1645 | Values: 477.3 482.3 488.8 492.8 533.4 30822.4 39802.2 41041.4 41514.6 42851.0 42013.6 42408.2 42478.7 42529.6 42586.6 8639.1 9546.4 9727.6 9742.2 9742.7 7224.2 11661.6 11944.8 11960.2 12214.4 11927.9 12006.2 12006.9 12007.5 12029.3 9870.4 11938.9 12015.5 12038.5 12046.7 11766.6 11849.9 11857.4 11878.9 11894.7 39830.4 40062.0 40139.6 40178.8 40245.0 3211.4 3322.5 3333.3 3339.2 3428.3 30175.3 31113.5 31351.0 31366.5 31660.7 3156.5 3238.9 3275.6 3294.9 3356.0 489447.1 581042.0 592491.0 595108.0 596683.0 59530.7 60625.7 60636.7 60717.7 60851.7 33749.5 34370.3 34409.5 63094.4 63403.7 16273.3 16438.0 16457.0 16473.4 16474.9 11322.8 11523.7 12425.9 12600.5 12721.4 17974.4 18311.0 18363.7 18416.6 18499.1 6526.8 6618.8 7950.7 8051.2 8058.6 867.5 915.2 945.9 946.2 1012.2 25863.7 28806.9 29311.0 29921.3 30064.1 1032.9 1058.3 1059.8 1077.1 1090.5 12314.8 12502.8 12750.7 15226.1 15303.6 541285.0 546354.0 546595.0 547412.0 547467.0 53881.2 54442.0 54530.5 54535.6 54553.9 1243.5 1347.7 1367.8 1392.6 1393.6 30127.7 30804.5 30826.5 31869.0 31907.5 47520.8 47529.8 47695.5 47783.8 48050.8 518407.4 521205.0 521891.8 523182.0 523350.0 26059.7 26392.7 26960.0 26971.3 27831.7 37238.0 46106.3 46532.8 46571.8 47025.5 37708.0 37734.4 38734.6 38962.6 39768.4 30082.4 32618.5 34269.3 37678.2 39084.2 36445.3 36913.0 37173.6 37414.8 37483.8 32327.8 33735.4 33886.0 33948.2 34044.5 24658.8 24753.1 25003.1 25353.0 25424.2 18:39:58 INFO - PROCESS | 1645 | 18:39:58 INFO - PROCESS | 1645 | [#2] jquery.html Cycles:180 Average:32899.18 Median:3143.51 stddev:93078.34 (2961.0%) stddev-sans-first:93307.36 18:39:58 INFO - PROCESS | 1645 | Values: 347.4 364.7 371.9 379.0 400.1 15565.8 16546.9 20963.8 21041.4 21152.7 20554.0 21105.7 21559.7 21610.4 21797.7 5984.6 6101.5 6103.9 6162.3 6204.6 6003.2 6096.4 6165.0 6190.5 6198.7 4190.2 4344.3 6722.9 6736.6 6809.4 6575.5 6683.6 6738.6 6740.8 6752.5 5719.5 5846.8 5850.1 5853.4 5856.5 25002.9 26417.6 26420.7 26517.9 26742.7 2696.9 2713.3 2720.0 3070.1 3216.9 15602.5 15855.6 16206.8 16260.4 16462.9 1645.2 1881.3 1885.8 1932.7 1935.3 96827.5 120101.4 122313.8 122690.0 122817.8 9530.1 9994.8 11043.2 11268.8 11359.0 14035.0 14069.1 14087.8 14106.4 14133.5 8358.5 8599.7 8661.3 8812.0 8828.0 7221.8 8660.8 8688.4 8697.6 8894.4 6630.3 6741.4 6751.3 6761.1 6786.9 1624.7 1835.4 1840.4 1841.3 1849.3 864.3 970.2 978.1 981.6 992.3 16194.8 21810.8 21838.2 22145.8 22300.0 911.8 944.9 959.3 961.8 1007.9 10042.5 11868.5 12007.6 12025.3 12062.0 543885.0 554220.0 561503.0 570620.0 574449.1 68891.3 69312.0 96316.0 103570.5 120458.4 1001.7 1022.0 1047.0 1048.3 1049.1 15098.8 19253.6 19253.7 19350.7 19713.8 39096.2 40142.8 44119.0 47440.0 51146.5 72771.6 74340.6 74545.6 74806.0 76613.2 7149.7 7887.3 7975.6 8306.1 8479.2 12772.8 13364.5 13372.2 13578.7 13656.1 11398.3 11442.3 11698.4 11751.4 11774.9 11107.7 11324.3 11354.8 11445.4 11748.8 10967.5 11361.4 11621.4 11623.3 11809.7 6002.0 6625.8 8015.4 8386.5 8495.8 8321.1 8781.6 9877.0 10853.3 11525.8 18:39:58 INFO - PROCESS | 1645 | 18:39:58 INFO - PROCESS | 1645 | [#3] mootools.html Cycles:180 Average:5762.64 Median:4875.37 stddev:3248.56 (66.6%) stddev-sans-first:3240.88 18:39:58 INFO - PROCESS | 1645 | Values: 1367.2 1379.2 1551.8 1749.4 1942.0 7845.4 8659.4 8928.6 8953.0 9030.1 8788.7 8858.3 8866.5 8889.2 8927.6 2394.1 2421.3 2422.1 2422.7 2426.9 2373.7 2396.4 2421.4 2422.4 2422.5 2385.6 2407.8 2416.3 2425.4 2426.7 2350.5 2370.1 2380.5 2386.8 2396.0 2345.7 2369.1 2371.9 2372.1 2376.4 8756.5 8766.5 8951.3 8978.7 8981.3 5283.2 5467.7 5472.0 5481.0 5484.4 3138.0 3682.4 3706.2 3764.4 3790.3 1107.4 1115.9 1116.5 1126.9 1131.9 11916.0 11960.5 12019.5 12020.0 12045.7 4457.1 4516.0 4705.0 4705.5 4721.4 7804.7 9297.1 9320.6 9328.5 9349.1 5296.7 5299.0 5303.6 5307.5 5312.9 4420.3 4583.0 4788.7 4962.0 4986.8 3225.5 3803.8 3849.7 3858.5 3887.5 1174.1 1184.8 1188.7 1200.5 1200.9 792.5 853.8 908.5 922.6 950.7 5880.0 5916.8 5933.6 6072.5 6079.8 406.7 940.1 940.9 973.1 1015.4 3902.3 3964.2 3985.2 4043.2 4105.4 10538.3 10987.3 11006.7 11016.4 11019.6 10955.6 10968.1 10968.3 10971.4 10977.3 7947.4 8336.5 8337.5 8337.8 8445.3 5886.3 5963.2 6021.8 6163.5 6239.3 10052.1 10240.1 10341.3 10343.5 10352.0 10085.9 10112.5 10166.2 10404.6 10538.8 7066.7 7311.5 7421.4 7581.8 7714.1 8433.6 8595.4 8602.7 8616.7 8632.6 7396.2 7412.9 7562.6 7670.8 7675.3 7394.0 7521.3 7578.5 7580.9 7619.1 6963.1 7086.0 7086.6 7087.6 7090.5 4975.5 5075.5 5090.4 5104.3 5250.7 4257.5 5073.8 5090.0 5139.6 5259.4 18:39:58 INFO - PROCESS | 1645 | 18:39:58 INFO - PROCESS | 1645 | [#4] prototype.html Cycles:180 Average:4243.86 Median:4589.72 stddev:1306.71 (28.5%) stddev-sans-first:1276.37 18:39:58 INFO - PROCESS | 1645 | Values: 297.9 298.1 308.2 312.6 314.3 5128.8 5300.2 5335.7 5357.5 5423.2 4204.6 5067.3 5156.4 5202.6 5309.8 3563.6 3848.3 4042.9 4083.4 4114.0 3688.3 3823.8 3845.3 3947.6 4136.3 3302.7 3779.2 4026.6 4068.5 4071.0 4014.3 4019.5 4041.3 4042.3 4053.3 3247.7 3404.9 3431.7 3542.6 3561.5 4787.4 4836.9 4901.6 4910.3 5206.8 3728.4 3818.1 4526.3 4535.9 4561.3 5526.4 5543.9 5549.6 5570.9 5619.5 3649.2 3782.8 3784.8 3805.4 3814.4 5810.9 5812.7 5817.1 5822.2 5838.7 3789.2 3819.6 3825.2 3826.1 3843.2 3245.7 3680.3 3893.3 3903.0 3904.0 3828.7 4060.1 4508.9 4523.2 4563.5 5161.9 5498.7 5631.7 5647.6 5665.1 1252.3 1298.3 1299.3 1301.2 1318.3 867.8 881.6 894.8 896.6 898.5 4504.4 5063.3 5178.4 5179.5 5189.4 4239.2 4350.0 4978.5 5436.9 5499.9 4815.2 4944.2 5070.7 5129.6 5158.7 1971.7 2097.7 2449.9 2494.4 2535.5 4028.7 4311.6 4522.3 4620.0 4924.5 4460.4 4835.5 4850.0 4867.7 4908.3 4326.9 4388.5 4516.6 4582.7 4953.7 3780.4 3898.4 4256.1 4266.9 4774.8 4696.4 4711.2 4725.6 4745.0 4745.2 4204.7 4430.6 4782.8 4914.5 4982.8 2941.5 2977.8 3191.6 3236.9 3338.7 4087.4 4596.7 4739.6 4808.9 5426.4 5354.8 5488.9 5529.3 5614.9 5617.4 5251.4 5437.3 5573.6 5608.8 5629.5 4505.2 5113.4 5135.3 5449.8 5493.4 4646.5 5456.7 5521.8 5532.4 5632.9 4554.3 4678.6 5454.3 5480.5 5481.6 18:39:58 INFO - PROCESS | 1645 | 18:39:58 INFO - PROCESS | 1645 | [#5] yui.html Cycles:180 Average:22293.48 Median:4211.74 stddev:33209.94 (788.5%) stddev-sans-first:33263.50 18:39:58 INFO - PROCESS | 1645 | Values: 693.5 738.3 751.1 799.6 855.9 14010.7 17873.0 17883.2 17922.1 17937.2 16022.9 16129.1 16160.0 16164.4 16202.9 664.0 840.4 868.8 871.2 872.0 680.9 694.9 848.0 870.7 874.3 892.5 893.8 894.8 895.1 896.2 697.9 889.0 889.3 889.8 890.8 641.3 659.0 662.1 665.1 668.3 15149.1 15231.9 15240.9 15241.5 15244.5 13245.9 13594.3 13775.1 13820.2 13989.3 11242.4 11565.7 11984.2 12065.9 12280.7 3779.9 3793.6 3821.2 3942.4 3965.1 112018.0 113316.0 113541.4 113590.4 113942.0 10425.6 10631.6 10832.5 11071.9 11085.1 22492.7 22802.5 22807.0 22823.9 22826.3 6512.7 7853.1 8004.8 8068.4 8116.3 4284.0 4458.0 4478.7 4528.9 4655.7 3592.4 3705.4 3707.8 3710.1 3714.4 608.9 637.2 648.7 668.9 682.5 582.7 658.8 669.0 679.7 680.7 8856.5 9197.0 9294.5 9445.8 9455.7 615.5 664.7 665.8 667.1 669.8 4139.5 4473.3 4508.5 4535.4 4655.6 92305.5 92561.5 92666.0 95934.0 98065.0 105584.0 109722.5 111983.0 115744.3 124221.0 73754.6 74099.0 98146.0 104642.0 108246.0 8665.9 8955.5 9869.4 9890.3 9997.0 53592.2 57313.4 58618.1 61635.0 62819.7 69562.0 85946.6 89703.0 90688.0 93240.5 10879.6 12326.3 12469.4 13057.8 13497.5 13184.3 14476.8 14789.4 15001.5 15419.6 11048.6 13179.0 13215.3 13240.4 13484.0 13399.0 13615.0 13695.4 13777.4 14117.6 11632.4 11839.6 12493.3 13999.6 14398.9 8121.9 10997.8 11450.4 11463.4 11572.1 9235.5 10048.6 11326.9 11490.3 11523.1 18:39:58 INFO - PROCESS | 1645 | -------- Summary: end -------- 18:39:58 INFO - PROCESS | 1645 | 18:39:59 INFO - TEST-INFO | 1645: exit 0 18:39:59 INFO - TEST-OK | dromaeo_css | took 1117998ms 18:39:59 INFO - TEST-START | kraken 18:39:59 INFO - operating with platform_type : mac_ 18:39:59 INFO - Initialising browser for kraken test... 18:39:59 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/tmpOzD7qb/profile http://localhost:49271/getInfo.html) 18:40:01 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 18:40:01 INFO - PROCESS | 1656 | colorDepth:24 18:40:01 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 18:40:01 INFO - PROCESS | 1656 | __metrics 18:40:01 INFO - PROCESS | 1656 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:40:01 INFO - PROCESS | 1656 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:40:02 INFO - TEST-INFO | 1656: exit 0 18:40:02 INFO - Browser initialized. 18:40:02 INFO - Running cycle 1/1 for kraken test... 18:40:02 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/tmpOzD7qb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:40:14 INFO - PROCESS | 1657 | RSS: Main: 234299392 18:40:14 INFO - PROCESS | 1657 | 18:40:17 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 18:40:18 INFO - PROCESS | 1657 | RSS: Main: 291991552 18:40:18 INFO - PROCESS | 1657 | 18:40:21 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 18:40:21 INFO - PROCESS | 1657 | RSS: Main: 288722944 18:40:21 INFO - PROCESS | 1657 | 18:40:25 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 18:40:25 INFO - PROCESS | 1657 | RSS: Main: 379338752 18:40:25 INFO - PROCESS | 1657 | 18:40:27 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 18:40:28 INFO - PROCESS | 1657 | RSS: Main: 281911296 18:40:28 INFO - PROCESS | 1657 | 18:40:30 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:40:31 INFO - PROCESS | 1657 | RSS: Main: 285011968 18:40:31 INFO - PROCESS | 1657 | 18:40:33 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 18:40:34 INFO - PROCESS | 1657 | RSS: Main: 321761280 18:40:34 INFO - PROCESS | 1657 | 18:40:37 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 18:40:37 INFO - PROCESS | 1657 | RSS: Main: 323153920 18:40:37 INFO - PROCESS | 1657 | 18:40:40 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 18:40:41 INFO - PROCESS | 1657 | RSS: Main: 321966080 18:40:41 INFO - PROCESS | 1657 | 18:40:42 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:40:43 INFO - PROCESS | 1657 | RSS: Main: 298639360 18:40:43 INFO - PROCESS | 1657 | 18:40:45 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:40:46 INFO - PROCESS | 1657 | RSS: Main: 302714880 18:40:46 INFO - PROCESS | 1657 | 18:40:48 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:40:49 INFO - PROCESS | 1657 | RSS: Main: 302854144 18:40:49 INFO - PROCESS | 1657 | 18:40:51 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:40:52 INFO - PROCESS | 1657 | RSS: Main: 315154432 18:40:52 INFO - PROCESS | 1657 | 18:40:55 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:40:56 INFO - PROCESS | 1657 | RSS: Main: 317603840 18:40:56 INFO - PROCESS | 1657 | 18:40:57 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 18:40:58 INFO - PROCESS | 1657 | RSS: Main: 361803776 18:40:58 INFO - PROCESS | 1657 | 18:40:58 INFO - PROCESS | 1657 | __start_tp_report 18:40:58 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 18:40:58 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 18:40:58 INFO - PROCESS | 1657 | |i|pagename|runs| 18:40:58 INFO - PROCESS | 1657 | |0;ai-astar;113;113;135;112;112;113;114;114;113;112 18:40:58 INFO - PROCESS | 1657 | |1;audio-beat-detection;177;137;167;164;127;161;130;105;161;107 18:40:58 INFO - PROCESS | 1657 | |2;audio-dft;184;182;187;181;185;183;184;182;182;184 18:40:58 INFO - PROCESS | 1657 | |3;audio-fft;79;73;76;155;59;58;57;65;140;57 18:40:58 INFO - PROCESS | 1657 | |4;audio-oscillator;83;86;129;71;76;91;121;69;71;119 18:40:58 INFO - PROCESS | 1657 | |5;imaging-gaussian-blur;87;84;88;84;86;83;88;84;87;84 18:40:58 INFO - PROCESS | 1657 | |6;imaging-darkroom;92;94;94;94;93;97;94;94;93;93 18:40:58 INFO - PROCESS | 1657 | |7;imaging-desaturate;84;86;87;83;83;87;85;83;84;84 18:40:58 INFO - PROCESS | 1657 | |8;json-parse-financial;53;54;56;54;54;53;54;54;53;53 18:40:58 INFO - PROCESS | 1657 | |9;json-stringify-tinderbox;66;66;66;74;73;77;72;73;71;73 18:40:58 INFO - PROCESS | 1657 | |10;stanford-crypto-aes;86;88;86;83;83;85;88;90;89;86 18:40:58 INFO - PROCESS | 1657 | |11;stanford-crypto-ccm;138;137;139;145;149;144;130;149;237;156 18:40:58 INFO - PROCESS | 1657 | |12;stanford-crypto-pbkdf2;168;169;172;168;175;168;173;169;170;171 18:40:58 INFO - PROCESS | 1657 | |13;stanford-crypto-sha256-iterative;68;66;69;67;68;67;67;68;66;68 18:40:58 INFO - PROCESS | 1657 | __end_tp_report 18:40:58 INFO - PROCESS | 1657 | __start_cc_report 18:40:58 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,2078 18:40:58 INFO - PROCESS | 1657 | __end_cc_report 18:40:58 INFO - PROCESS | 1657 | __startTimestamp1472694058148__endTimestamp 18:40:58 INFO - PROCESS | 1657 | 18:40:58 INFO - PROCESS | 1657 | ------- Summary: start ------- 18:40:58 INFO - PROCESS | 1657 | Number of tests: 14 18:40:58 INFO - PROCESS | 1657 | 18:40:58 INFO - PROCESS | 1657 | [#0] ai-astar Cycles:10 Average:115.10 Median:113.00 stddev:7.03 (6.2%) stddev-sans-first:7.42 18:40:58 INFO - PROCESS | 1657 | Values: 113.0 113.0 135.0 112.0 112.0 113.0 114.0 114.0 113.0 112.0 18:40:58 INFO - PROCESS | 1657 | 18:40:58 INFO - PROCESS | 1657 | [#1] audio-beat-detection Cycles:10 Average:143.60 Median:161.00 stddev:25.86 (16.1%) stddev-sans-first:24.44 18:40:58 INFO - PROCESS | 1657 | Values: 177.0 137.0 167.0 164.0 127.0 161.0 130.0 105.0 161.0 107.0 18:40:58 INFO - PROCESS | 1657 | 18:40:58 INFO - PROCESS | 1657 | [#2] audio-dft Cycles:10 Average:183.40 Median:184.00 stddev:1.78 (1.0%) stddev-sans-first:1.87 18:40:58 INFO - PROCESS | 1657 | Values: 184.0 182.0 187.0 181.0 185.0 183.0 184.0 182.0 182.0 184.0 18:40:58 INFO - PROCESS | 1657 | 18:40:58 INFO - PROCESS | 1657 | [#3] audio-fft Cycles:10 Average:81.90 Median:62.00 stddev:35.69 (57.6%) stddev-sans-first:37.84 18:40:58 INFO - PROCESS | 1657 | Values: 79.0 73.0 76.0 155.0 59.0 58.0 57.0 65.0 140.0 57.0 18:40:58 INFO - PROCESS | 1657 | 18:40:58 INFO - PROCESS | 1657 | [#4] audio-oscillator Cycles:10 Average:91.60 Median:73.50 stddev:22.91 (31.2%) stddev-sans-first:24.08 18:40:58 INFO - PROCESS | 1657 | Values: 83.0 86.0 129.0 71.0 76.0 91.0 121.0 69.0 71.0 119.0 18:40:58 INFO - PROCESS | 1657 | 18:40:58 INFO - PROCESS | 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:85.50 Median:86.50 stddev:1.90 (2.2%) stddev-sans-first:1.94 18:40:58 INFO - PROCESS | 1657 | Values: 87.0 84.0 88.0 84.0 86.0 83.0 88.0 84.0 87.0 84.0 18:40:58 INFO - PROCESS | 1657 | 18:40:58 INFO - PROCESS | 1657 | [#6] imaging-darkroom Cycles:10 Average:93.80 Median:94.00 stddev:1.32 (1.4%) stddev-sans-first:1.22 18:40:58 INFO - PROCESS | 1657 | Values: 92.0 94.0 94.0 94.0 93.0 97.0 94.0 94.0 93.0 93.0 18:40:58 INFO - PROCESS | 1657 | 18:40:58 INFO - PROCESS | 1657 | [#7] imaging-desaturate Cycles:10 Average:84.60 Median:84.50 stddev:1.58 (1.9%) stddev-sans-first:1.66 18:40:58 INFO - PROCESS | 1657 | Values: 84.0 86.0 87.0 83.0 83.0 87.0 85.0 83.0 84.0 84.0 18:40:58 INFO - PROCESS | 1657 | 18:40:58 INFO - PROCESS | 1657 | [#8] json-parse-financial Cycles:10 Average:53.80 Median:54.00 stddev:0.92 (1.7%) stddev-sans-first:0.93 18:40:58 INFO - PROCESS | 1657 | Values: 53.0 54.0 56.0 54.0 54.0 53.0 54.0 54.0 53.0 53.0 18:40:58 INFO - PROCESS | 1657 | 18:40:58 INFO - PROCESS | 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:71.10 Median:73.00 stddev:3.84 (5.3%) stddev-sans-first:3.61 18:40:58 INFO - PROCESS | 1657 | Values: 66.0 66.0 66.0 74.0 73.0 77.0 72.0 73.0 71.0 73.0 18:40:58 INFO - PROCESS | 1657 | 18:40:58 INFO - PROCESS | 1657 | [#10] stanford-crypto-aes Cycles:10 Average:86.40 Median:87.00 stddev:2.37 (2.7%) stddev-sans-first:2.51 18:40:58 INFO - PROCESS | 1657 | Values: 86.0 88.0 86.0 83.0 83.0 85.0 88.0 90.0 89.0 86.0 18:40:58 INFO - PROCESS | 1657 | 18:40:58 INFO - PROCESS | 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:152.40 Median:147.00 stddev:30.63 (20.8%) stddev-sans-first:32.04 18:40:58 INFO - PROCESS | 1657 | Values: 138.0 137.0 139.0 145.0 149.0 144.0 130.0 149.0 237.0 156.0 18:40:58 INFO - PROCESS | 1657 | 18:40:58 INFO - PROCESS | 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.30 Median:170.50 stddev:2.41 (1.4%) stddev-sans-first:2.40 18:40:58 INFO - PROCESS | 1657 | Values: 168.0 169.0 172.0 168.0 175.0 168.0 173.0 169.0 170.0 171.0 18:40:58 INFO - PROCESS | 1657 | 18:40:58 INFO - PROCESS | 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:68.00 stddev:0.97 (1.4%) stddev-sans-first:1.00 18:40:58 INFO - PROCESS | 1657 | Values: 68.0 66.0 69.0 67.0 68.0 67.0 67.0 68.0 66.0 68.0 18:40:58 INFO - PROCESS | 1657 | -------- Summary: end -------- 18:40:58 INFO - PROCESS | 1657 | 18:40:59 INFO - TEST-INFO | 1657: exit 0 18:40:59 INFO - TEST-OK | kraken | took 59941ms 18:40:59 INFO - SUITE-END | took 1177s 18:40:59 INFO - Completed test suite (00:19:38) 18:40:59 INFO - javascript benchmark 18:40:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3345.2305969626987, 3350.2629421527263, 3359.6304406515296, 3381.9154521136984, 3435.4846772984065, 5536.390996990338, 5583.190437386582, 5627.380988091307, 5678.914816277759, 5694.886102277952, 5663.292088488939, 5691.544676425887, 5696.11710184921, 5697.145428185774, 5713.028785106531, 5148.742562871851, 5185.092608793446, 5201.271821944912, 5206.010857936999, 5266.183741520071, 5171.405288391846, 5223.085959957011, 5238.13570760824, 5240.554552863011, 5247.606429517782, 5193.948060519384, 5198.1423065194385, 5214.0, 5214.869628259305, 5260.8158714445, 5243.790248390079, 5282.154855223146, 5288.286081379003, 5297.894042119136, 5323.653962492425, 5045.268436076748, 5100.719460429675, 5140.588752899759, 5187.19598462239, 5203.765830537635, 5585.1063829787445, 5679.09134538476, 5708.257926469532, 5724.856878578027, 5725.570582206351, 6185.474234690057, 6185.690715464254, 6201.379862013808, 6202.689865506752, 6218.751249949996, 6115.816525504242, 6158.076288556752, 6202.62784232943, 6284.622922624657, 6312.337204593543, 5671.404502527175, 5700.8859822803315, 5704.71476426177, 5753.913691294634, 5756.28046494184, 6253.062040693932, 6275.811725648238, 6293.590916590407, 6293.905591416041, 6317.652529110851, 5762.88474230513, 6032.4570788629235, 6041.78853740117, 6048.9697551512845, 6058.212432383762, 6184.257889053252, 6212.06818977148, 6233.283172435137, 6261.5616906816085, 6269.937300626935, 6168.9691551542855, 6182.1345011698395, 6184.845378865564, 6194.876102477925, 6221.564490485713, 5845.649261044351, 5880.411958804086, 6216.285127210338, 6230.719617617218, 6233.3143354231, 4834.984653222792, 4870.0, 4880.731559764176, 4904.877378065506, 4931.211006238985, 4366.366876802846, 4371.475422949203, 4376.737395756265, 4378.058717375716, 4435.6229720473475, 5576.247206627135, 5611.114973235517, 5638.069718496402, 5673.489385955201, 5704.11586204139, 6145.446909778052, 6163.3528479509905, 6193.194884664965, 6206.0, 6237.09562113491, 5696.458836410535, 5699.344575373802, 5712.057510510719, 5727.226824378656, 5774.740136693859, 5356.571474282048, 5366.838994830161, 5369.4630536946, 5377.5429088527135, 5383.569314454834, 6239.251289845066, 6254.562180647311, 6268.655223962634, 6292.905606415999, 6350.96824515893, 6234.9688251558455, 6248.125262463168, 6256.06159076124, 6256.968715156395, 6298.937010629835, 6286.277078136073, 6306.84232894163, 6307.621542707452, 6318.462930650946, 6348.492120630246, 5957.880842383215, 6094.51243900478, 6120.693965301806, 6124.289394097845, 6163.506919446523, 6045.486133678686, 6230.221222347207, 6244.781432649836, 6247.5001999840815, 6250.874982500233, 6013.188219590299, 6040.469647764176, 6055.182550355646, 6069.969650151897, 6113.388661133851, 5882.323532793613, 5992.340842507408, 6020.969895150672, 6033.034714445667, 6117.96941015292, 5843.474087332161, 5863.794767183128, 5872.265966754156, 5887.028640274307, 5896.056630939029, 5974.2233509643465, 6029.306629737636, 6060.939335616337, 6063.605865618721, 6064.3026052002815, 4887.875788568578, 5739.024365857731, 5812.0, 5883.617564858442, 5909.852253693692], "value": 5666.342303974538, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [477.3317355702019, 482.31992890025066, 488.777606189184, 492.76593618031416, 533.3999250842802, 30822.38355232894, 39802.2039559209, 41041.38437923433, 41514.584854151486, 42851.0, 42013.57986420143, 42408.15183696324, 42478.725638230826, 42529.57470425295, 42586.57413425865, 8639.092895246004, 9546.379485333431, 9727.56225969833, 9742.22062235023, 9742.707718768414, 7224.169220539627, 11661.59184428545, 11944.820827687592, 11960.222585531956, 12214.389280535983, 11927.880721192807, 12006.219595726294, 12006.879931200707, 12007.519699212022, 12029.338386388745, 9870.358426702312, 11938.925496705338, 12015.519379224821, 12038.518459261619, 12046.69883252917, 11766.647000589996, 11849.881501184964, 11857.407129643483, 11878.940605297004, 11894.702632434171, 39830.40254396186, 40061.99845003869, 40139.59860401388, 40178.79463616067, 40244.993875153064, 3211.3898359311656, 3322.4850148227065, 3333.250018745787, 3339.215284408162, 3428.3486137633768, 30175.32331770257, 31113.533297000562, 31351.0, 31366.52950205749, 31660.683393166004, 3156.4949608062598, 3238.854251558662, 3275.6233033201247, 3294.917627059319, 3355.983220083884, 489447.1055289437, 581042.0, 592491.0, 595108.0, 596683.0, 59530.70234648799, 60625.69687151536, 60636.696816515636, 60717.69641151766, 60851.695741521005, 33749.48127334276, 34370.312593747985, 34409.483857742154, 63094.36905630977, 63403.682981584796, 16273.267702953395, 16438.0, 16457.0, 16473.423430180126, 16474.917625412036, 11322.830157547643, 11523.654290371302, 12425.943794777364, 12600.495980160875, 12721.363931803373, 17974.37089701854, 18311.0, 18363.72454413185, 18416.63166736658, 18499.075046247635, 6526.804195874131, 6618.768343107968, 7950.681972721142, 8051.2159285135895, 8058.556779377191, 867.4968518259394, 915.1534830281962, 945.8581212818133, 946.2051876423837, 1012.2054187462807, 25863.741362586134, 28806.85596572004, 29310.97411590584, 29921.251968700955, 30064.098077057723, 1032.9431881246603, 1058.2856571813995, 1059.7562560611102, 1077.06833588945, 1090.547422819521, 12314.81527777084, 12502.81245781314, 12750.68123296925, 15226.121878819758, 15303.61740956485, 541285.0, 546354.0, 546595.0, 547412.0, 547467.0, 53881.1917821233, 54442.0, 54530.454695452536, 54535.63660908509, 54553.908921822134, 1243.5461056714234, 1347.6630842289624, 1367.7948307753718, 1392.5891861900966, 1393.6097892590092, 30127.69353551494, 30804.53793193149, 30826.53760193554, 31869.0, 31907.521387178745, 47520.7623961878, 47529.76235118802, 47695.523044770496, 47783.761081194374, 48050.759746201045, 518407.4079629729, 521205.0, 521891.78108218434, 523182.0, 523350.0, 26059.73940260573, 26392.736072639796, 26960.0, 26971.325716857235, 27831.721682783722, 37238.0, 46106.30840537462, 46532.7673361631, 46571.76714116408, 47025.529744702115, 37708.0, 37734.43398348972, 38734.612653874225, 38962.610373897034, 39768.403473947335, 30082.398352033273, 32618.51072233871, 34269.314613708084, 37678.24643507169, 39084.2183156341, 36445.27109457849, 36913.0, 37173.628263717015, 37414.8129259352, 37483.81258093692, 32327.838360808044, 33735.444249481305, 33886.0, 33948.151296217795, 34044.489332659534, 24658.753412465645, 24753.133640322503, 25003.12489062874, 25353.0, 25424.237272881102], "value": 19826.304263687754, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.3573888306631, 364.68272602835526, 371.89215127612977, 378.9602091780362, 400.0937875711514, 15565.844341556582, 16546.91726541367, 20963.790362096373, 21041.368758937257, 21152.682709759345, 20554.0, 21105.68341474879, 21559.676604850938, 21610.35168944934, 21797.67303490445, 5984.611000284989, 6101.511879049665, 6103.938960610403, 6162.291336496315, 6204.5967012144065, 6003.219581454405, 6096.420840020191, 6165.0, 6190.535709821759, 6198.721057552421, 4190.245755763961, 4344.348347747848, 6722.865542689144, 6736.629485378302, 6809.353111454404, 6575.506836987245, 6683.565568238049, 6738.629375384352, 6740.797776066725, 6752.527323087385, 5719.542436605103, 5846.824595262149, 5850.12248162779, 5853.443922827325, 5856.472917437451, 25002.874985624956, 26417.603735943976, 26420.73579264202, 26517.93928242868, 26742.73257267422, 2696.8651567421552, 2713.2674177972035, 2719.986400068007, 3070.078976307099, 3216.9356612867846, 15602.531924042298, 15855.603609909844, 16206.75689864653, 16260.430884918971, 16462.917685411616, 1645.1617692913144, 1881.3321270948752, 1885.764279465067, 1932.671445854208, 1935.2839449403812, 96827.51586242094, 120101.39949300286, 122313.77686223113, 122690.0, 122817.77182228153, 9530.142287194185, 9994.800103997879, 11043.171762117874, 11268.830967535494, 11359.0, 14035.017548771692, 14069.085509441853, 14087.788683169761, 14106.435742570182, 14133.505327313494, 8358.498490090615, 8599.742007739778, 8661.263792577702, 8812.0, 8828.0, 7221.819454513679, 8660.826783464296, 8688.435251708619, 8697.565121744014, 8894.42186257891, 6630.30381809903, 6741.359570840762, 6751.278424001848, 6761.053452516651, 6786.8642627148165, 1624.6831867785904, 1835.4034938644993, 1840.4142180236788, 1841.3392647624323, 1849.3342396737164, 864.3085531574715, 970.1559643110539, 978.1490103609763, 981.6417007792104, 992.2905122837207, 16194.75707864383, 21810.781892180876, 21838.23566175176, 22145.778542214695, 22299.996500157533, 911.7600063913205, 944.8866136063716, 959.2949182350967, 961.7980224152866, 1007.9092881640687, 10042.497875106215, 11868.525258989717, 12007.579734709243, 12025.278483291031, 12062.035037197003, 543885.0, 554220.0, 561503.0, 570620.0, 574449.1277543586, 68891.27771805745, 69312.0, 96316.0, 103570.48214759029, 120458.3977080109, 1001.6944831826363, 1021.99489002556, 1046.9528871200814, 1048.3343077146067, 1049.0768124050787, 15098.773518397013, 19253.614927701088, 19253.711194332376, 19350.709739354203, 19713.802861971195, 39096.2180756389, 40142.799286003385, 44119.0, 47440.0, 51146.48853511417, 72771.63614181895, 74340.62829685817, 74545.62727186547, 74806.0, 76613.23386766062, 7149.678264478007, 7887.250711182429, 7975.601219938864, 8306.086330503518, 8479.236868681848, 12772.808407874076, 13364.532241371895, 13372.197668139943, 13578.728425431238, 13656.112352697042, 11398.259113157617, 11442.256253343507, 11698.415079246173, 11751.412429378326, 11774.882251177378, 11107.722611899735, 11324.263922844988, 11354.829677555008, 11445.427728613702, 11748.823767643322, 10967.53230523126, 11361.431928403712, 11621.360825154698, 11623.302601843916, 11809.704757381134, 6001.969990150022, 6625.768098116543, 8015.438919275594, 8386.454880432751, 8495.787605309917, 8321.08468068524, 8781.604827782892, 9877.0, 10853.294535855144, 11525.82711259315], "value": 9740.277787311683, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1367.2001878900844, 1379.1587131849565, 1551.7827504149418, 1749.3702267183812, 1942.0192802634658, 7845.411594130442, 8659.437136586117, 8928.598213080402, 8953.0, 9030.096990300966, 8788.736337909857, 8858.291336693064, 8866.512341821197, 8889.199972002518, 8927.598258078386, 2394.090245706629, 2421.309926670899, 2422.055398394619, 2422.672939153218, 2426.9271921842374, 2373.703287089114, 2396.400899775056, 2421.382547450398, 2422.406510404953, 2422.4670572474115, 2385.5667219436164, 2407.7712617301418, 2416.2630397728685, 2425.405775584984, 2426.7330593634774, 2350.506393657317, 2370.12305446984, 2380.500094980048, 2386.820988425875, 2396.0, 2345.7302410222874, 2369.1234243329905, 2371.8814059296965, 2372.1341710275797, 2376.3821406434276, 8756.474611523265, 8766.474011559327, 8951.32865035125, 8978.685745998924, 8981.326400519987, 5283.207518872162, 5467.726613669301, 5472.0, 5480.972595137038, 5484.396716361197, 3138.0272115644225, 3682.3739964206147, 3706.22169344438, 3764.3788774852114, 3790.2608991246716, 1107.3577325151393, 1115.8605174353206, 1116.4529380603526, 1126.8873112688748, 1131.8585176852894, 11915.98714109293, 11960.46177921987, 12019.459124339426, 12020.0, 12045.698857528545, 4457.086297309044, 4516.0, 4704.976859950254, 4705.529447055335, 4721.386219791405, 7804.726834560764, 9297.116773906468, 9320.580573874191, 9328.486933218737, 9349.111834375723, 5296.682199068068, 5298.97350513245, 5303.628745987744, 5307.4692530746615, 5312.92030619541, 4420.270655341897, 4582.977085114553, 4788.736619485892, 4962.007598480318, 4986.750662466935, 3225.483922572377, 3803.8288277027045, 3849.7488316296754, 3858.5369755629504, 3887.475190849199, 1174.1018121137338, 1184.8341232227494, 1188.6552899659002, 1200.5137919142762, 1200.9279443233434, 792.4730054513799, 853.8463076646264, 908.5094049213483, 922.6355589542228, 950.6577632052456, 5880.0, 5916.792912248136, 5933.58464907461, 6072.544559158081, 6079.787207447718, 406.6685651194267, 940.05994005994, 940.9388389754644, 973.0609961387295, 1015.4262841494532, 3902.317094508506, 3964.207158568298, 3985.1830374773103, 4043.1509383029893, 4105.4252404663375, 10538.262321637565, 10987.340759554452, 11006.669799906016, 11016.394098324668, 11019.559217631366, 10955.616553420592, 10968.122550195965, 10968.287061340987, 10971.396573188551, 10977.28647637901, 7947.364210863233, 8336.541490217975, 8337.458065225741, 8337.791555211046, 8445.282151017109, 5886.293644762656, 5963.165156878127, 6021.849453763516, 6163.537734669828, 6239.313675495782, 10052.095311422006, 10240.053884593835, 10341.276110672181, 10343.534540945526, 10352.0, 10085.89914100879, 10112.544935477774, 10166.186705063725, 10404.606940403599, 10538.78922421532, 7066.681999309941, 7311.4516411268305, 7421.443391745749, 7581.810454738676, 7714.11287701899, 8433.578321084044, 8595.44129631572, 8602.741917742478, 8616.741497755078, 8632.611532480927, 7396.223396543501, 7412.851742965003, 7562.584057876869, 7670.8082297943, 7675.347595454449, 7394.0, 7521.323080922743, 7578.469507134448, 7580.886286705814, 7619.123800762762, 6963.09479767627, 7085.964570177116, 7086.610236437045, 7087.574745515286, 7090.503664743418, 4975.452700203047, 5075.467075956972, 5090.400559384647, 5104.285400043923, 5250.684958902325, 4257.467816522934, 5073.797048118182, 5090.0, 5139.6145289102715, 5259.42146363892], "value": 4610.942698655143, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.9240293725101, 298.1131134873751, 308.2386505331831, 312.56241262232885, 314.2740269976355, 5128.794848206067, 5300.178472336785, 5335.733213339332, 5357.464253574645, 5423.186522021694, 4204.5585213552595, 5067.315912351832, 5156.355455568054, 5202.557782588475, 5309.840704778853, 3563.6258192889763, 3848.26882892251, 4042.939355909664, 4083.3670781028986, 4113.950942509656, 3688.2807852468645, 3823.833730712132, 3845.3270677631444, 3947.5657677655436, 4136.27615167346, 3302.6862448067395, 3779.2441511697634, 4026.617471340218, 4068.5214141908687, 4070.9796451017705, 4014.3376342903384, 4019.5176578810438, 4041.2523683118425, 4042.3128068228325, 4053.3312003519386, 3247.675232476757, 3404.9148771280916, 3431.673990970854, 3542.6280240574624, 3561.51919490868, 4787.366948178488, 4836.879078023042, 4901.607871370282, 4910.312556242093, 5206.843794686166, 3728.440733889911, 3818.0673905876993, 4526.275795872678, 4535.866033491627, 4561.292999585036, 5526.392096869341, 5543.867714741158, 5549.5837812164245, 5570.86072848178, 5619.522340601054, 3649.2154186849953, 3782.829772660237, 3784.8107594620433, 3805.4101614249557, 3814.351560234739, 5810.891173222306, 5812.709364531756, 5817.0983497557945, 5822.244652199308, 5838.7080645967535, 3789.223209242154, 3819.6058438669684, 3825.1967086911504, 3826.1391186983265, 3843.1929294847837, 3245.7241134503834, 3680.337539242962, 3893.279743247508, 3902.9609703902597, 3903.980480097581, 3828.73198876076, 4060.127072679329, 4508.932366014513, 4523.185826551186, 4563.53966730643, 5161.870953226199, 5498.697571633598, 5631.662100273997, 5647.632903861236, 5665.065264231355, 1252.348778635123, 1298.3121941476043, 1299.311364976545, 1301.1737546657941, 1318.2947123289186, 867.8437881181447, 881.6032785246665, 894.7673604862782, 896.5830888636815, 898.5192921786812, 4504.391907092566, 5063.291139240509, 5178.404483484447, 5179.456157103526, 5189.377274727057, 4239.215745087169, 4350.0, 4978.477259887732, 5436.912617476521, 5499.900019996016, 4815.167578403585, 4944.184209605375, 5070.6957582545165, 5129.615278854101, 5158.664686795421, 1971.6943873699313, 2097.748270207585, 2449.8530088194407, 2494.3764058985253, 2535.5151951088037, 4028.697847661437, 4311.590398912161, 4522.321651752263, 4619.976900115478, 4924.507549245047, 4460.353248778909, 4835.516448355207, 4849.951500485021, 4867.683600565952, 4908.318760929303, 4326.870193894189, 4388.4733831940375, 4516.638668906479, 4582.747948862844, 4953.678010929278, 3780.3951367781024, 3898.4347269645177, 4256.063316311735, 4266.935995960125, 4774.76126193682, 4696.393501606641, 4711.246200607886, 4725.637181409295, 4744.952550474451, 4745.169595320736, 4204.738578426399, 4430.556944305544, 4782.832600858953, 4914.459409464885, 4982.775775090057, 2941.4558306713325, 2977.761779057627, 3191.632962209376, 3236.8543415545887, 3338.732901367933, 4087.427760113646, 4596.701214421052, 4739.559127049503, 4808.869915569909, 5426.430224826337, 5354.839354819362, 5488.862778430571, 5529.253550770594, 5614.887702246014, 5617.410171931889, 5251.350311533448, 5437.26596909428, 5573.6098473106495, 5608.775648973995, 5629.493345598916, 4505.189065968158, 5113.4375218726655, 5135.305349234669, 5449.782008719607, 5493.395726470005, 4646.535346465326, 5456.672599644034, 5521.82495065331, 5532.3637781654015, 5632.859178520406, 4554.27131658933, 4678.625709943264, 5454.318210223722, 5480.547654871542, 5481.561475081904], "value": 3838.9644469339137, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [693.549193024534, 738.287552511826, 751.0911796725961, 799.6041959230182, 855.9015713192977, 14010.719785604282, 17873.0, 17883.19525621347, 17922.103894805256, 17937.192826322847, 16022.878398512108, 16129.112898790561, 16160.030398176117, 16164.35342586298, 16202.918985405056, 663.9834004149886, 840.3823189955378, 868.7697760093548, 871.2202578692066, 872.0451106038909, 680.9387155156035, 694.9374556289932, 848.0078308379211, 870.7257213977576, 874.3005595523588, 892.451142547332, 893.8301722672704, 894.8255090257402, 895.1451363947415, 896.224765577777, 697.8708938846279, 888.9911100889005, 889.3107841422884, 889.7997950461126, 890.7505898348472, 641.3490307338055, 658.9505787065989, 662.066486254379, 665.1187176990459, 668.3216535216759, 15149.091054536762, 15231.923840380727, 15240.923795381063, 15241.466548670744, 15244.46644367453, 13245.86754132456, 13594.252316122609, 13775.104618199884, 13820.170789752647, 13989.30053497321, 11242.437878106062, 11565.653030409101, 11984.161108722394, 12065.939670301592, 12280.692982675415, 3779.8677046303246, 3793.639604237593, 3821.159344944108, 3942.42834788954, 3965.1474932889278, 112018.0, 113316.0, 113541.43229283883, 113590.43204784006, 113941.99999999917, 10425.582976680847, 10631.569017818565, 10832.458377081115, 11071.889281107247, 11085.113190944705, 22492.662610060863, 22802.543949120925, 22807.0, 22823.88588057049, 22826.31521054371, 6512.706928188243, 7853.057633084067, 8004.796733464121, 8068.3948703847445, 8116.310113640288, 4283.971846756818, 4458.0, 4478.731276123443, 4528.88677783058, 4655.650826188049, 3592.3713350163616, 3705.444183372462, 3707.8331475083687, 3710.109573702292, 3714.405695088773, 608.8978948103946, 637.1812221295613, 648.7015972652632, 668.8762578922915, 682.5222344358968, 582.7144699097496, 658.7595527632379, 668.9564279723646, 679.6907407129744, 680.7304377336233, 8856.468611883307, 9196.954015230014, 9294.488803115893, 9445.811083778286, 9455.716328510156, 615.5473083522883, 664.6743095882982, 665.7769647168157, 667.0927538547571, 669.8158006548239, 4139.503259608866, 4473.261911784584, 4508.504064552896, 4535.353032039477, 4655.557722016403, 92305.53847230856, 92561.53719231361, 92666.0, 95934.0, 98065.0, 105584.0, 109722.45138774415, 111983.0, 115744.26383604253, 124221.0, 73754.63122684353, 74099.0, 98146.0, 104642.0, 108246.0, 8665.870011949952, 8955.462672239686, 9869.407835529892, 9890.32802342327, 9997.0, 53592.196117057494, 57313.426865730806, 58618.12072818826, 61635.0, 62819.6859015702, 69561.95657065249, 85946.57026714826, 89703.0, 90688.0, 93240.53379733057, 10879.619213327496, 12326.32205228721, 12469.438875250817, 13057.804132938203, 13497.460101595827, 13184.340782961006, 14476.765776248192, 14789.408423662933, 15001.549953501413, 15419.61450963735, 11048.558057677603, 13179.0, 13215.339233038118, 13240.404181812035, 13484.00178032715, 13399.0, 13615.046946713635, 13695.383707732977, 13777.380017899419, 14117.576472705836, 11632.418379080842, 11839.638441579329, 12493.25040497573, 13999.580012599637, 14398.856011439751, 8121.862939188401, 10997.780044399227, 11450.427478626201, 11463.426828658701, 11572.132090093108, 9235.492047937429, 10048.598056077675, 11326.943365283121, 11490.253133546294, 11523.078153747512], "value": 7635.664523306636, "unit": "score"}], "name": "dromaeo_css", "value": 7272.257728218721, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 135.0, 112.0, 112.0, 113.0, 114.0, 114.0, 113.0, 112.0], "value": 115.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 137.0, 167.0, 164.0, 127.0, 161.0, 130.0, 105.0, 161.0, 107.0], "value": 143.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 182.0, 187.0, 181.0, 185.0, 183.0, 184.0, 182.0, 182.0, 184.0], "value": 183.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 73.0, 76.0, 155.0, 59.0, 58.0, 57.0, 65.0, 140.0, 57.0], "value": 81.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 86.0, 129.0, 71.0, 76.0, 91.0, 121.0, 69.0, 71.0, 119.0], "value": 91.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 88.0, 84.0, 86.0, 83.0, 88.0, 84.0, 87.0, 84.0], "value": 85.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 94.0, 94.0, 94.0, 93.0, 97.0, 94.0, 94.0, 93.0, 93.0], "value": 93.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 87.0, 83.0, 83.0, 87.0, 85.0, 83.0, 84.0, 84.0], "value": 84.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 54.0, 56.0, 54.0, 54.0, 53.0, 54.0, 54.0, 53.0, 53.0], "value": 53.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 74.0, 73.0, 77.0, 72.0, 73.0, 71.0, 73.0], "value": 71.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 88.0, 86.0, 83.0, 83.0, 85.0, 88.0, 90.0, 89.0, 86.0], "value": 86.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 137.0, 139.0, 145.0, 149.0, 144.0, 130.0, 149.0, 237.0, 156.0], "value": 152.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 169.0, 172.0, 168.0, 175.0, 168.0, 173.0, 169.0, 170.0, 171.0], "value": 170.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 69.0, 67.0, 68.0, 67.0, 67.0, 68.0, 66.0, 68.0], "value": 67.4, "unit": "score"}], "name": "kraken", "value": 1480.9, "alertThreshold": 2.0}]} 18:40:59 INFO - Return code: 0 18:40:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:40:59 INFO - # TBPL SUCCESS # 18:40:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:40:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:40:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:40:59 INFO - 2016-08-31 18:40:59.498 screenresolution[1661:9705] starting screenresolution argv=screenresolution get 18:40:59 INFO - 2016-08-31 18:40:59.501 screenresolution[1661:9705] Display 0: 1600x1200x32@60 18:40:59 INFO - 2016-08-31 18:40:59.507 screenresolution[1662:9708] starting screenresolution argv=screenresolution list 18:40:59 INFO - Available Modes on Display 0 18:40:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:40:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:40:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:40:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:40:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:40:59 INFO - Intel Iris: 18:40:59 INFO - Chipset Model: Intel Iris 18:40:59 INFO - Type: GPU 18:40:59 INFO - Bus: Built-In 18:40:59 INFO - VRAM (Dynamic, Max): 1536 MB 18:40:59 INFO - Vendor: Intel (0x8086) 18:40:59 INFO - Device ID: 0x0a2e 18:40:59 INFO - Revision ID: 0x0009 18:40:59 INFO - Displays: 18:40:59 INFO - Display: 18:40:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:40:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:40:59 INFO - Display Serial Number: 1600x1200 60 18:40:59 INFO - Main Display: Yes 18:40:59 INFO - Mirror: Off 18:40:59 INFO - Online: Yes 18:40:59 INFO - Rotation: Supported 18:40:59 INFO - Return code: 0 18:40:59 INFO - Running post-action listener: _resource_record_post_action 18:40:59 INFO - [mozharness: 2016-09-01 01:40:59.747371Z] Finished run-tests step (success) 18:40:59 INFO - Running post-run listener: _resource_record_post_run 18:40:59 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 37307392; Write bytes: 706403328; Read time: 247; Write time: 2021 18:40:59 INFO - TinderboxPrint: CPU usage
53.5% 18:40:59 INFO - TinderboxPrint: I/O read bytes / time
37,307,392 / 247 18:40:59 INFO - TinderboxPrint: I/O write bytes / time
706,403,328 / 2,021 18:40:59 INFO - TinderboxPrint: CPU idle
2,257.0 (46.5%) 18:40:59 INFO - TinderboxPrint: CPU system
966.4 (19.9%) 18:40:59 INFO - TinderboxPrint: CPU user
1,630.3 (33.6%) 18:40:59 INFO - TinderboxPrint: Swap in / out
443,465,728 / 0 18:40:59 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 403051008; Write bytes: 397694464; Read time: 29905; Write time: 868 18:40:59 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 35197952; Write bytes: 296031744; Read time: 225; Write time: 1116 18:41:00 INFO - Running post-run listener: _upload_blobber_files 18:41:00 INFO - Blob upload gear active. 18:41:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:41:00 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 18:41:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:41:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:41:00 INFO - (blobuploader) - INFO - Open directory for files ... 18:41:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:41:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:41:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:41:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:41:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:41:01 INFO - (blobuploader) - INFO - Done attempting. 18:41:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:41:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:41:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:41:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:41:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:41:02 INFO - (blobuploader) - INFO - Done attempting. 18:41:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:41:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:41:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:41:04 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:41:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:41:04 INFO - (blobuploader) - INFO - Done attempting. 18:41:04 INFO - (blobuploader) - INFO - Iteration through files over. 18:41:04 INFO - Return code: 0 18:41:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:41:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:41:04 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0cc9d958c99c386f49c47655cf2dd4068fb2d4ec9ffc7123fcb3dd329429c4167b24a80f54acd471ff06183c649f77c05990b77f0d744d94b68f01bf8e648dc7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0331e0bdeb3e2ffa88db82565800c32381444b040d3adaeb5d2a0775757111441ec9d684e0610c56a4f8e3633dc104d6bcb6afaf811ac476bf03e331bdffa4b5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/79dd76d97c01e20c17f97e23a634b451c89e61f7194cbc6af6132d38108139d13a1d4551f07ec7b8d8f9d09dc7f02780623927bfd79fb5c9b7e7baaadb0b383a"} 18:41:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:41:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:41:04 INFO - Contents: 18:41:04 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0cc9d958c99c386f49c47655cf2dd4068fb2d4ec9ffc7123fcb3dd329429c4167b24a80f54acd471ff06183c649f77c05990b77f0d744d94b68f01bf8e648dc7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0331e0bdeb3e2ffa88db82565800c32381444b040d3adaeb5d2a0775757111441ec9d684e0610c56a4f8e3633dc104d6bcb6afaf811ac476bf03e331bdffa4b5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/79dd76d97c01e20c17f97e23a634b451c89e61f7194cbc6af6132d38108139d13a1d4551f07ec7b8d8f9d09dc7f02780623927bfd79fb5c9b7e7baaadb0b383a"} 18:41:04 INFO - Running post-run listener: copy_logs_to_upload_dir 18:41:04 INFO - Copying logs to upload dir... 18:41:04 INFO - mkdir: /builds/slave/test/build/upload/logs 18:41:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.188735 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-08-31 18:41:04.861717) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-31 18:41:04.864897) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.6Ce2dIxkcZ/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test/properties RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.9S2VLVFUD1/Listeners TMPDIR=/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0cc9d958c99c386f49c47655cf2dd4068fb2d4ec9ffc7123fcb3dd329429c4167b24a80f54acd471ff06183c649f77c05990b77f0d744d94b68f01bf8e648dc7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0331e0bdeb3e2ffa88db82565800c32381444b040d3adaeb5d2a0775757111441ec9d684e0610c56a4f8e3633dc104d6bcb6afaf811ac476bf03e331bdffa4b5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/79dd76d97c01e20c17f97e23a634b451c89e61f7194cbc6af6132d38108139d13a1d4551f07ec7b8d8f9d09dc7f02780623927bfd79fb5c9b7e7baaadb0b383a"} build_url:https://queue.taskcluster.net/v1/task/SqFZLK2kRYqCHNmgJFZ1YA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011282 build_url: 'https://queue.taskcluster.net/v1/task/SqFZLK2kRYqCHNmgJFZ1YA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0cc9d958c99c386f49c47655cf2dd4068fb2d4ec9ffc7123fcb3dd329429c4167b24a80f54acd471ff06183c649f77c05990b77f0d744d94b68f01bf8e648dc7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0331e0bdeb3e2ffa88db82565800c32381444b040d3adaeb5d2a0775757111441ec9d684e0610c56a4f8e3633dc104d6bcb6afaf811ac476bf03e331bdffa4b5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/79dd76d97c01e20c17f97e23a634b451c89e61f7194cbc6af6132d38108139d13a1d4551f07ec7b8d8f9d09dc7f02780623927bfd79fb5c9b7e7baaadb0b383a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-31 18:41:04.898971) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 18:41:04.899307) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.6Ce2dIxkcZ/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.9S2VLVFUD1/Listeners TMPDIR=/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005148 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 18:41:04.948112) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-31 18:41:04.948441) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-31 18:41:04.948730) ========= ========= Total master_lag: 4.25 =========