builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0320 starttime: 1477351917.22 results: success (0) buildid: 20161024140622 builduid: 26397ab0c33c4500915c55f624c972a3 revision: bf9235ab0289e3fc88cc0fdba9f02ba7983aa695 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-24 16:31:57.221063) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-24 16:31:57.221508) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-24 16:31:57.221796) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-24 16:31:57.245754) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 16:31:57.246176) ========= 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.Sz7xkMX6RZ/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.Ryat8Qt8Ko/Listeners TMPDIR=/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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-10-24 16:31:57-- 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: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 30.4M=0s 2016-10-24 16:31:57 (30.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.121661 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 16:31:57.396899) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 16:31:57.397213) ========= 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.Sz7xkMX6RZ/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.Ryat8Qt8Ko/Listeners TMPDIR=/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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.026363 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 16:31:57.445614) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-24 16:31:57.449027) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev bf9235ab0289e3fc88cc0fdba9f02ba7983aa695 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev bf9235ab0289e3fc88cc0fdba9f02ba7983aa695 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Sz7xkMX6RZ/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.Ryat8Qt8Ko/Listeners TMPDIR=/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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-10-24 16:31:57,524 truncating revision to first 12 chars 2016-10-24 16:31:57,524 Setting DEBUG logging. 2016-10-24 16:31:57,525 attempt 1/10 2016-10-24 16:31:57,525 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/bf9235ab0289?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-24 16:31:58,288 unpacking tar archive at: mozilla-aurora-bf9235ab0289/testing/mozharness/ program finished with exit code 0 elapsedTime=1.032359 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-24 16:31:58.503402) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-24 16:31:58.506760) ========= script_repo_revision: bf9235ab0289e3fc88cc0fdba9f02ba7983aa695 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-24 16:31:58.507147) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-24 16:31:58.507409) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-24 16:31:58.526413) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-10-24 16:31:58.526798) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Sz7xkMX6RZ/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.Ryat8Qt8Ko/Listeners TMPDIR=/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 16:31:58 INFO - MultiFileLogger online at 20161024 16:31:58 in /builds/slave/test 16:31:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 16:31:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:31:58 INFO - {'append_to_log': False, 16:31:58 INFO - 'base_work_dir': '/builds/slave/test', 16:31:58 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 16:31:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:31:58 INFO - 'branch': 'Mozilla-Aurora', 16:31:58 INFO - 'buildbot_json_path': 'buildprops.json', 16:31:58 INFO - 'config_files': ('talos/mac_config.py',), 16:31:58 INFO - 'default_actions': ('clobber', 16:31:58 INFO - 'read-buildbot-config', 16:31:58 INFO - 'download-and-extract', 16:31:58 INFO - 'populate-webroot', 16:31:58 INFO - 'create-virtualenv', 16:31:58 INFO - 'install', 16:31:58 INFO - 'run-tests'), 16:31:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:31:58 INFO - 'download_minidump_stackwalk': True, 16:31:58 INFO - 'download_symbols': 'ondemand', 16:31:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:31:58 INFO - 'tooltool.py': '/tools/tooltool.py', 16:31:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:31:58 INFO - '/tools/misc-python/virtualenv.py')}, 16:31:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:31:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:31:58 INFO - 'installer_path': 'installer.exe', 16:31:58 INFO - 'log_level': 'info', 16:31:58 INFO - 'log_name': 'talos', 16:31:58 INFO - 'log_to_console': True, 16:31:58 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:31:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:31:58 INFO - 'opt_config_files': (), 16:31:58 INFO - 'pip_index': False, 16:31:58 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:31:58 INFO - 'cmd': ('bash', 16:31:58 INFO - '-c', 16:31:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:31:58 INFO - 'enabled': True, 16:31:58 INFO - 'halt_on_failure': False, 16:31:58 INFO - 'name': 'check_screen_resolution'},), 16:31:58 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:31:58 INFO - 'cmd': ('bash', 16:31:58 INFO - '-c', 16:31:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:31:58 INFO - 'enabled': True, 16:31:58 INFO - 'halt_on_failure': False, 16:31:58 INFO - 'name': 'check_screen_resolution'},), 16:31:58 INFO - 'run_cmd_checks_enabled': True, 16:31:58 INFO - 'sps_profile': False, 16:31:58 INFO - 'sps_profile_interval': 0, 16:31:58 INFO - 'suite': 'dromaeojs', 16:31:58 INFO - 'system_bits': '32', 16:31:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:31:58 INFO - 'title': 't-yosemite-r7-0320', 16:31:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:31:58 INFO - 'use_talos_json': True, 16:31:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:31:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:31:58 INFO - 'work_dir': 'build'} 16:31:58 INFO - [mozharness: 2016-10-24 23:31:58.732784Z] Running clobber step. 16:31:58 INFO - Running pre-action listener: _resource_record_pre_action 16:31:58 INFO - Running main action method: clobber 16:31:58 INFO - rmtree: /builds/slave/test/build 16:31:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:32:00 INFO - Running post-action listener: _resource_record_post_action 16:32:00 INFO - [mozharness: 2016-10-24 23:32:00.919907Z] Finished clobber step (success) 16:32:00 INFO - [mozharness: 2016-10-24 23:32:00.920053Z] Running read-buildbot-config step. 16:32:00 INFO - Running pre-action listener: _resource_record_pre_action 16:32:00 INFO - Running main action method: read_buildbot_config 16:32:00 INFO - Using buildbot properties: 16:32:00 INFO - { 16:32:00 INFO - "project": "", 16:32:00 INFO - "product": "firefox", 16:32:00 INFO - "script_repo_revision": "production", 16:32:00 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 16:32:00 INFO - "repository": "", 16:32:00 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 16:32:00 INFO - "buildid": "20161024140622", 16:32:00 INFO - "pgo_build": "False", 16:32:00 INFO - "basedir": "/builds/slave/test", 16:32:00 INFO - "buildnumber": 109, 16:32:00 INFO - "slavename": "t-yosemite-r7-0320", 16:32:00 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 16:32:00 INFO - "platform": "yosemite_r7", 16:32:00 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 16:32:00 INFO - "branch": "mozilla-aurora", 16:32:00 INFO - "repo_path": "releases/mozilla-aurora", 16:32:00 INFO - "revision": "bf9235ab0289e3fc88cc0fdba9f02ba7983aa695", 16:32:00 INFO - "stage_platform": "macosx64", 16:32:00 INFO - "builduid": "26397ab0c33c4500915c55f624c972a3", 16:32:00 INFO - "slavebuilddir": "test" 16:32:00 INFO - } 16:32:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/WC1P55XORRylbGVOut0Z5A/artifacts/public/build/firefox-51.0a2.en-US.mac.dmg. 16:32:00 INFO - Running post-action listener: _resource_record_post_action 16:32:00 INFO - [mozharness: 2016-10-24 23:32:00.923546Z] Finished read-buildbot-config step (success) 16:32:00 INFO - [mozharness: 2016-10-24 23:32:00.923678Z] Running download-and-extract step. 16:32:00 INFO - Running pre-action listener: _resource_record_pre_action 16:32:00 INFO - Running main action method: download_and_extract 16:32:00 INFO - mkdir: /builds/slave/test/build/tests 16:32:00 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')]} 16:32:00 INFO - trying https://queue.taskcluster.net/v1/task/WC1P55XORRylbGVOut0Z5A/artifacts/public/build/firefox-51.0a2.en-US.mac.test_packages.json 16:32:00 INFO - Downloading https://queue.taskcluster.net/v1/task/WC1P55XORRylbGVOut0Z5A/artifacts/public/build/firefox-51.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a2.en-US.mac.test_packages.json 16:32:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WC1P55XORRylbGVOut0Z5A/artifacts/public/build/firefox-51.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.mac.test_packages.json'}, attempt #1 16:32:02 INFO - Downloaded 1199 bytes. 16:32:02 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.mac.test_packages.json 16:32:02 INFO - Using the following test package requirements: 16:32:02 INFO - {u'common': [u'firefox-51.0a2.en-US.mac.common.tests.zip'], 16:32:02 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.mac.common.tests.zip', 16:32:02 INFO - u'firefox-51.0a2.en-US.mac.cppunittest.tests.zip'], 16:32:02 INFO - u'gtest': [u'firefox-51.0a2.en-US.mac.common.tests.zip', 16:32:02 INFO - u'firefox-51.0a2.en-US.mac.gtest.tests.zip'], 16:32:02 INFO - u'jittest': [u'firefox-51.0a2.en-US.mac.common.tests.zip', 16:32:02 INFO - u'jsshell-mac.zip'], 16:32:02 INFO - u'mochitest': [u'firefox-51.0a2.en-US.mac.common.tests.zip', 16:32:02 INFO - u'firefox-51.0a2.en-US.mac.mochitest.tests.zip'], 16:32:02 INFO - u'mozbase': [u'firefox-51.0a2.en-US.mac.common.tests.zip'], 16:32:02 INFO - u'reftest': [u'firefox-51.0a2.en-US.mac.common.tests.zip', 16:32:02 INFO - u'firefox-51.0a2.en-US.mac.reftest.tests.zip'], 16:32:02 INFO - u'talos': [u'firefox-51.0a2.en-US.mac.common.tests.zip', 16:32:02 INFO - u'firefox-51.0a2.en-US.mac.talos.tests.zip'], 16:32:02 INFO - u'web-platform': [u'firefox-51.0a2.en-US.mac.common.tests.zip', 16:32:02 INFO - u'firefox-51.0a2.en-US.mac.web-platform.tests.zip'], 16:32:02 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.mac.common.tests.zip', 16:32:02 INFO - u'firefox-51.0a2.en-US.mac.xpcshell.tests.zip']} 16:32:02 INFO - Downloading packages: [u'firefox-51.0a2.en-US.mac.common.tests.zip'] for test suite category: common 16:32:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WC1P55XORRylbGVOut0Z5A/artifacts/public/build/firefox-51.0a2.en-US.mac.common.tests.zip 16:32:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WC1P55XORRylbGVOut0Z5A/artifacts/public/build/firefox-51.0a2.en-US.mac.common.tests.zip'}, attempt #1 16:32:02 INFO - Fetch https://queue.taskcluster.net/v1/task/WC1P55XORRylbGVOut0Z5A/artifacts/public/build/firefox-51.0a2.en-US.mac.common.tests.zip into memory 16:32:02 INFO - Expected file size: 16438616 16:32:09 INFO - Downloading packages: [u'firefox-51.0a2.en-US.mac.common.tests.zip', u'firefox-51.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 16:32:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WC1P55XORRylbGVOut0Z5A/artifacts/public/build/firefox-51.0a2.en-US.mac.common.tests.zip 16:32:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WC1P55XORRylbGVOut0Z5A/artifacts/public/build/firefox-51.0a2.en-US.mac.common.tests.zip'}, attempt #1 16:32:09 INFO - Fetch https://queue.taskcluster.net/v1/task/WC1P55XORRylbGVOut0Z5A/artifacts/public/build/firefox-51.0a2.en-US.mac.common.tests.zip into memory 16:32:10 INFO - Expected file size: 16438616 16:32:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WC1P55XORRylbGVOut0Z5A/artifacts/public/build/firefox-51.0a2.en-US.mac.talos.tests.zip 16:32:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WC1P55XORRylbGVOut0Z5A/artifacts/public/build/firefox-51.0a2.en-US.mac.talos.tests.zip'}, attempt #1 16:32:17 INFO - Fetch https://queue.taskcluster.net/v1/task/WC1P55XORRylbGVOut0Z5A/artifacts/public/build/firefox-51.0a2.en-US.mac.talos.tests.zip into memory 16:32:17 INFO - Expected file size: 14414081 16:32: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')]} 16:32:18 INFO - trying https://queue.taskcluster.net/v1/task/WC1P55XORRylbGVOut0Z5A/artifacts/public/build/firefox-51.0a2.en-US.mac.dmg 16:32:18 INFO - Downloading https://queue.taskcluster.net/v1/task/WC1P55XORRylbGVOut0Z5A/artifacts/public/build/firefox-51.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a2.en-US.mac.dmg 16:32:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WC1P55XORRylbGVOut0Z5A/artifacts/public/build/firefox-51.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.mac.dmg'}, attempt #1 16:32:21 INFO - Downloaded 115439382 bytes. 16:32:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WC1P55XORRylbGVOut0Z5A/artifacts/public/build/firefox-51.0a2.en-US.mac.dmg 16:32:21 INFO - mkdir: /builds/slave/test/properties 16:32:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:32:21 INFO - Writing to file /builds/slave/test/properties/build_url 16:32:21 INFO - Contents: 16:32:21 INFO - build_url:https://queue.taskcluster.net/v1/task/WC1P55XORRylbGVOut0Z5A/artifacts/public/build/firefox-51.0a2.en-US.mac.dmg 16:32:21 INFO - Running post-action listener: _resource_record_post_action 16:32:21 INFO - Running post-action listener: set_extra_try_arguments 16:32:21 INFO - [mozharness: 2016-10-24 23:32:21.788406Z] Finished download-and-extract step (success) 16:32:21 INFO - [mozharness: 2016-10-24 23:32:21.788498Z] Running populate-webroot step. 16:32:21 INFO - Running pre-action listener: _resource_record_pre_action 16:32:21 INFO - Running main action method: populate_webroot 16:32:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:32:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:32:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:32:21 INFO - '--tppagecycles', 16:32:21 INFO - '7'], 16:32:21 INFO - 'tests': ['tsvgm']}}, 16:32:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:32:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:32:21 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:32:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:32:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:32:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:32:21 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:32:21 INFO - 'talos_options': ['--disable-e10s'], 16:32:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:32:21 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:32:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:32:21 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:32:21 INFO - 'talos_options': ['--disable-e10s'], 16:32:21 INFO - 'tests': ['damp', 'tps']}, 16:32:21 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:32:21 INFO - 'tests': ['damp', 'tps']}, 16:32:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:32:21 INFO - 'tests': ['dromaeo_dom']}, 16:32:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:32:21 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:32:21 INFO - 'tests': ['basic_compositor_video']}, 16:32:21 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:32:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:32:21 INFO - 'tests': ['a11yr', 16:32:21 INFO - 'ts_paint', 16:32:21 INFO - 'tpaint', 16:32:21 INFO - 'sessionrestore', 16:32:21 INFO - 'sessionrestore_no_auto_restore', 16:32:21 INFO - 'tabpaint']}, 16:32:21 INFO - 'other-e10s': {'tests': ['a11yr', 16:32:21 INFO - 'ts_paint', 16:32:21 INFO - 'tpaint', 16:32:21 INFO - 'sessionrestore', 16:32:21 INFO - 'sessionrestore_no_auto_restore', 16:32:21 INFO - 'tabpaint']}, 16:32:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:32:21 INFO - 'ts_paint', 16:32:21 INFO - 'tpaint', 16:32:21 INFO - 'sessionrestore', 16:32:21 INFO - 'sessionrestore_no_auto_restore', 16:32:21 INFO - 'tabpaint']}, 16:32:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:32:21 INFO - 'ts_paint', 16:32:21 INFO - 'tpaint', 16:32:21 INFO - 'sessionrestore', 16:32:21 INFO - 'sessionrestore_no_auto_restore', 16:32:21 INFO - 'tabpaint']}, 16:32:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:32:21 INFO - 'tests': ['a11yr', 16:32:21 INFO - 'ts_paint', 16:32:21 INFO - 'tpaint', 16:32:21 INFO - 'sessionrestore', 16:32:21 INFO - 'sessionrestore_no_auto_restore', 16:32:21 INFO - 'tabpaint']}, 16:32:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:32:21 INFO - 'tests': ['a11yr', 16:32:21 INFO - 'ts_paint', 16:32:21 INFO - 'tpaint', 16:32:21 INFO - 'sessionrestore', 16:32:21 INFO - 'sessionrestore_no_auto_restore', 16:32:21 INFO - 'tabpaint']}, 16:32:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:32:21 INFO - 'tests': ['tsvgx', 16:32:21 INFO - 'tsvgr_opacity', 16:32:21 INFO - 'tart', 16:32:21 INFO - 'tscrollx', 16:32:21 INFO - 'cart']}, 16:32:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:32:21 INFO - 'tsvgr_opacity', 16:32:21 INFO - 'tart', 16:32:21 INFO - 'tscrollx', 16:32:21 INFO - 'cart']}, 16:32:21 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:32:21 INFO - 'talos_options': ['--disable-e10s'], 16:32:21 INFO - 'tests': ['tp5o']}, 16:32:21 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:32:21 INFO - 'tests': ['tp5o']}, 16:32:21 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:32:21 INFO - 'talos_options': ['--disable-e10s', 16:32:21 INFO - '--xperf_path', 16:32:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:32:21 INFO - 'tests': ['tp5n']}, 16:32:21 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:32:21 INFO - 'talos_options': ['--xperf_path', 16:32:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:32:21 INFO - 'tests': ['tp5n']}}, 16:32:21 INFO - 'talos.zip': {'path': '', 16:32:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:32:21 INFO - Running post-action listener: _resource_record_post_action 16:32:21 INFO - [mozharness: 2016-10-24 23:32:21.807127Z] Finished populate-webroot step (success) 16:32:21 INFO - [mozharness: 2016-10-24 23:32:21.807259Z] Running create-virtualenv step. 16:32:21 INFO - Running pre-action listener: _resource_record_pre_action 16:32:21 INFO - Running main action method: create_virtualenv 16:32:21 INFO - Creating virtualenv /builds/slave/test/build/venv 16:32:21 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 16:32:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:32:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:32:21 INFO - Using real prefix '/tools/python27' 16:32:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:32:22 INFO - Installing distribute.............................................................................................................................................................................................done. 16:32:25 INFO - Installing pip.................done. 16:32:25 INFO - Return code: 0 16:32:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:32:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:32:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:32:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:25 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 0x1093e4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10935a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99f849d0f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091f8d30>, '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 0x10905edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091f0b20>, '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.Sz7xkMX6RZ/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.Ryat8Qt8Ko/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 16:32:25 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 16:32:25 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 16:32:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Sz7xkMX6RZ/Render', 16:32:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:32:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:32:25 INFO - 'HOME': '/Users/cltbld', 16:32:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:32:25 INFO - 'LOGNAME': 'cltbld', 16:32:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:25 INFO - 'MOZ_NO_REMOTE': '1', 16:32:25 INFO - 'NO_EM_RESTART': '1', 16:32:25 INFO - 'PAGER': '/bin/cat', 16:32:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:32:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:25 INFO - 'PWD': '/builds/slave/test', 16:32:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:32:25 INFO - 'SHELL': '/bin/bash', 16:32:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ryat8Qt8Ko/Listeners', 16:32:25 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 16:32:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:32:25 INFO - 'USER': 'cltbld', 16:32:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:32:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:32:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:25 INFO - 'XPC_FLAGS': '0x0', 16:32:25 INFO - 'XPC_SERVICE_NAME': '0', 16:32:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:32:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:25 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 16:32:25 INFO - Cleaning up... 16:32:25 INFO - Return code: 0 16:32:25 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:32:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:32:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:32:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:25 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 0x1093e4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10935a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99f849d0f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091f8d30>, '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 0x10905edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091f0b20>, '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.Sz7xkMX6RZ/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.Ryat8Qt8Ko/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 16:32:25 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 16:32:25 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 16:32:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Sz7xkMX6RZ/Render', 16:32:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:32:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:32:25 INFO - 'HOME': '/Users/cltbld', 16:32:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:32:25 INFO - 'LOGNAME': 'cltbld', 16:32:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:25 INFO - 'MOZ_NO_REMOTE': '1', 16:32:25 INFO - 'NO_EM_RESTART': '1', 16:32:25 INFO - 'PAGER': '/bin/cat', 16:32:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:32:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:25 INFO - 'PWD': '/builds/slave/test', 16:32:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:32:25 INFO - 'SHELL': '/bin/bash', 16:32:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ryat8Qt8Ko/Listeners', 16:32:25 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 16:32:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:32:25 INFO - 'USER': 'cltbld', 16:32:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:32:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:32:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:25 INFO - 'XPC_FLAGS': '0x0', 16:32:25 INFO - 'XPC_SERVICE_NAME': '0', 16:32:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:32:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:26 INFO - Downloading/unpacking psutil>=3.1.1 16:32: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 16:32: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 16:32: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 16:32: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 16:32: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 16:32: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 16:32:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:32:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:32:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:32:29 INFO - Installing collected packages: psutil 16:32:29 INFO - Running setup.py install for psutil 16:32:29 INFO - building 'psutil._psutil_osx' extension 16:32:29 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 16:32:29 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 16:32:29 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 16:32:29 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 16:32:29 INFO - building 'psutil._psutil_posix' extension 16:32:29 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 16:32:29 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:32:29 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:32:29 INFO - ^ 16:32:30 INFO - 1 warning generated. 16:32:30 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 16:32:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:32:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:32:30 INFO - Successfully installed psutil 16:32:30 INFO - Cleaning up... 16:32:30 INFO - Return code: 0 16:32:30 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:32: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')]} 16:32:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32: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')]} 16:32:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1093e4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10935a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99f849d0f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091f8d30>, '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 0x10905edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091f0b20>, '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.Sz7xkMX6RZ/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.Ryat8Qt8Ko/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 16:32: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:32: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 mozsystemmonitor==0.3 16:32:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Sz7xkMX6RZ/Render', 16:32:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:32:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:32:30 INFO - 'HOME': '/Users/cltbld', 16:32:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:32:30 INFO - 'LOGNAME': 'cltbld', 16:32:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:30 INFO - 'MOZ_NO_REMOTE': '1', 16:32:30 INFO - 'NO_EM_RESTART': '1', 16:32:30 INFO - 'PAGER': '/bin/cat', 16:32:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:32:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:30 INFO - 'PWD': '/builds/slave/test', 16:32:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:32:30 INFO - 'SHELL': '/bin/bash', 16:32:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ryat8Qt8Ko/Listeners', 16:32:30 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 16:32:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:32:30 INFO - 'USER': 'cltbld', 16:32:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:32:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:32:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:30 INFO - 'XPC_FLAGS': '0x0', 16:32:30 INFO - 'XPC_SERVICE_NAME': '0', 16:32:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:32:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:30 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:32: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 16:32: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 16:32: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 16:32: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 16:32: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 16:32: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 16:32:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:32:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:32:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:32:33 INFO - Installing collected packages: mozsystemmonitor 16:32:33 INFO - Running setup.py install for mozsystemmonitor 16:32:33 INFO - Successfully installed mozsystemmonitor 16:32:33 INFO - Cleaning up... 16:32:33 INFO - Return code: 0 16:32:33 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:32:33 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')]} 16:32:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:33 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')]} 16:32:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:33 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 0x1093e4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10935a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99f849d0f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091f8d30>, '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 0x10905edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091f0b20>, '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.Sz7xkMX6RZ/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.Ryat8Qt8Ko/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 16:32:33 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 16:32:33 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 16:32:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Sz7xkMX6RZ/Render', 16:32:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:32:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:32:33 INFO - 'HOME': '/Users/cltbld', 16:32:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:32:33 INFO - 'LOGNAME': 'cltbld', 16:32:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:33 INFO - 'MOZ_NO_REMOTE': '1', 16:32:33 INFO - 'NO_EM_RESTART': '1', 16:32:33 INFO - 'PAGER': '/bin/cat', 16:32:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:32:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:33 INFO - 'PWD': '/builds/slave/test', 16:32:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:32:33 INFO - 'SHELL': '/bin/bash', 16:32:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ryat8Qt8Ko/Listeners', 16:32:33 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 16:32:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:32:33 INFO - 'USER': 'cltbld', 16:32:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:32:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:32:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:33 INFO - 'XPC_FLAGS': '0x0', 16:32:33 INFO - 'XPC_SERVICE_NAME': '0', 16:32:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:32:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:33 INFO - Downloading/unpacking jsonschema==2.5.1 16:32: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 16:32: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 16:32: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 16:32: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 16:32: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 16:32: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 16:32:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:32:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:32:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:32:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:32:36 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 16:32:36 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 16:32:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:32:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:32:36 INFO - Downloading functools32-3.2.3-2.tar.gz 16:32:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:32:36 INFO - warning: no files found matching '*.txt' 16:32:36 INFO - no previously-included directories found matching 'build' 16:32:36 INFO - no previously-included directories found matching 'dist' 16:32:36 INFO - no previously-included directories found matching '.git*' 16:32:36 INFO - Installing collected packages: jsonschema, functools32 16:32:36 INFO - Running setup.py install for functools32 16:32:36 INFO - warning: no files found matching '*.txt' 16:32:36 INFO - no previously-included directories found matching 'build' 16:32:36 INFO - no previously-included directories found matching 'dist' 16:32:36 INFO - no previously-included directories found matching '.git*' 16:32:36 INFO - Successfully installed jsonschema functools32 16:32:36 INFO - Cleaning up... 16:32:36 INFO - Return code: 0 16:32:36 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:32:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:32:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:32:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:36 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 0x1093e4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10935a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99f849d0f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091f8d30>, '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 0x10905edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091f0b20>, '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.Sz7xkMX6RZ/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.Ryat8Qt8Ko/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 16:32:36 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 16:32:36 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 16:32:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Sz7xkMX6RZ/Render', 16:32:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:32:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:32:36 INFO - 'HOME': '/Users/cltbld', 16:32:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:32:36 INFO - 'LOGNAME': 'cltbld', 16:32:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:36 INFO - 'MOZ_NO_REMOTE': '1', 16:32:36 INFO - 'NO_EM_RESTART': '1', 16:32:36 INFO - 'PAGER': '/bin/cat', 16:32:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:32:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:36 INFO - 'PWD': '/builds/slave/test', 16:32:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:32:36 INFO - 'SHELL': '/bin/bash', 16:32:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ryat8Qt8Ko/Listeners', 16:32:36 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 16:32:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:32:36 INFO - 'USER': 'cltbld', 16:32:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:32:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:32:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:36 INFO - 'XPC_FLAGS': '0x0', 16:32:36 INFO - 'XPC_SERVICE_NAME': '0', 16:32:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:32:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:37 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:32:37 INFO - Cleaning up... 16:32:37 INFO - Return code: 0 16:32:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:32:37 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')]} 16:32:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:37 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')]} 16:32:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:37 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 0x1093e4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10935a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99f849d0f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091f8d30>, '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 0x10905edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091f0b20>, '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.Sz7xkMX6RZ/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.Ryat8Qt8Ko/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 16:32:37 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 16:32:37 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 16:32:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Sz7xkMX6RZ/Render', 16:32:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:32:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:32:37 INFO - 'HOME': '/Users/cltbld', 16:32:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:32:37 INFO - 'LOGNAME': 'cltbld', 16:32:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:37 INFO - 'MOZ_NO_REMOTE': '1', 16:32:37 INFO - 'NO_EM_RESTART': '1', 16:32:37 INFO - 'PAGER': '/bin/cat', 16:32:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:32:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:37 INFO - 'PWD': '/builds/slave/test', 16:32:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:32:37 INFO - 'SHELL': '/bin/bash', 16:32:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ryat8Qt8Ko/Listeners', 16:32:37 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 16:32:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:32:37 INFO - 'USER': 'cltbld', 16:32:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:32:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:32:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:37 INFO - 'XPC_FLAGS': '0x0', 16:32:37 INFO - 'XPC_SERVICE_NAME': '0', 16:32:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:32:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:37 INFO - Downloading/unpacking blobuploader==1.2.4 16:32:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:32:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:32:37 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 16:32:37 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 16:32:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:32:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:32:39 INFO - Downloading blobuploader-1.2.4.tar.gz 16:32:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:32:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:32:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:32:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:32:40 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 16:32:40 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 16:32:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:32:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:32:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:32:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:32:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:32:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:32:40 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 16:32:40 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 16:32:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:32:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:32:41 INFO - Downloading docopt-0.6.1.tar.gz 16:32:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:32:41 INFO - Installing collected packages: blobuploader, requests, docopt 16:32:41 INFO - Running setup.py install for blobuploader 16:32:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:32:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:32:41 INFO - Running setup.py install for requests 16:32:41 INFO - Running setup.py install for docopt 16:32:42 INFO - Successfully installed blobuploader requests docopt 16:32:42 INFO - Cleaning up... 16:32:42 INFO - Return code: 0 16:32:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:32:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:32:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:32:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:42 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 0x1093e4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10935a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99f849d0f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091f8d30>, '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 0x10905edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091f0b20>, '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.Sz7xkMX6RZ/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.Ryat8Qt8Ko/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 16:32:42 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 16:32:42 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 16:32:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Sz7xkMX6RZ/Render', 16:32:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:32:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:32:42 INFO - 'HOME': '/Users/cltbld', 16:32:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:32:42 INFO - 'LOGNAME': 'cltbld', 16:32:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:42 INFO - 'MOZ_NO_REMOTE': '1', 16:32:42 INFO - 'NO_EM_RESTART': '1', 16:32:42 INFO - 'PAGER': '/bin/cat', 16:32:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:32:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:42 INFO - 'PWD': '/builds/slave/test', 16:32:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:32:42 INFO - 'SHELL': '/bin/bash', 16:32:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ryat8Qt8Ko/Listeners', 16:32:42 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 16:32:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:32:42 INFO - 'USER': 'cltbld', 16:32:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:32:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:32:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:42 INFO - 'XPC_FLAGS': '0x0', 16:32:42 INFO - 'XPC_SERVICE_NAME': '0', 16:32:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:32:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:32:42 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-MZE5W5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:32:42 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-68O0p9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:32:42 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-x69IAx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:32:42 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-BflPjJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:32:42 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-R8IKo_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:32:42 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-ttJrvh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:32:43 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-aqWP6c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:32:43 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-UsrRvT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:32:43 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-SAlE2g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:32:43 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-Y3qUxe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:32:43 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-JAcz6Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:32:43 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-ny4hqY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:32:43 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-yB363X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:32:43 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-zRPIQ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:32:43 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-55SrX0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:32:43 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-VWqqQq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:32:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:32:44 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-J7qbga-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:32:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:32:44 INFO - Running setup.py install for manifestparser 16:32:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:32:44 INFO - Running setup.py install for mozcrash 16:32:44 INFO - Running setup.py install for mozdebug 16:32:44 INFO - Running setup.py install for mozdevice 16:32:44 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:32:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:32:44 INFO - Running setup.py install for mozfile 16:32:44 INFO - Running setup.py install for mozhttpd 16:32:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:32:45 INFO - Running setup.py install for mozinfo 16:32:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:32:45 INFO - Running setup.py install for mozInstall 16:32:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:32:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:32:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:32:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:32:45 INFO - Running setup.py install for mozleak 16:32:45 INFO - Running setup.py install for mozlog 16:32:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:32:45 INFO - Running setup.py install for moznetwork 16:32:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:32:45 INFO - Running setup.py install for mozprocess 16:32:45 INFO - Running setup.py install for mozprofile 16:32:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:32:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:32:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:32:46 INFO - Running setup.py install for mozrunner 16:32:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:32:46 INFO - Running setup.py install for mozscreenshot 16:32:46 INFO - Running setup.py install for moztest 16:32:46 INFO - Running setup.py install for mozversion 16:32:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:32:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:32:46 INFO - Cleaning up... 16:32:46 INFO - Return code: 0 16:32:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:32:46 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')]} 16:32:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:47 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')]} 16:32:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:47 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 0x1093e4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10935a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99f849d0f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091f8d30>, '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 0x10905edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091f0b20>, '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.Sz7xkMX6RZ/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.Ryat8Qt8Ko/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 16:32:47 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 16:32:47 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 16:32:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Sz7xkMX6RZ/Render', 16:32:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:32:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:32:47 INFO - 'HOME': '/Users/cltbld', 16:32:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:32:47 INFO - 'LOGNAME': 'cltbld', 16:32:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:47 INFO - 'MOZ_NO_REMOTE': '1', 16:32:47 INFO - 'NO_EM_RESTART': '1', 16:32:47 INFO - 'PAGER': '/bin/cat', 16:32:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:32:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:47 INFO - 'PWD': '/builds/slave/test', 16:32:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:32:47 INFO - 'SHELL': '/bin/bash', 16:32:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ryat8Qt8Ko/Listeners', 16:32:47 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 16:32:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:32:47 INFO - 'USER': 'cltbld', 16:32:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:32:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:32:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:47 INFO - 'XPC_FLAGS': '0x0', 16:32:47 INFO - 'XPC_SERVICE_NAME': '0', 16:32:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:32:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:32:47 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-yA21x6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:32:47 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)) 16:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:32:47 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-DPDB3F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:32:47 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)) 16:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:32:47 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-yKUS02-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:32:47 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)) 16:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:32:47 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-wUFVL6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:32:47 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)) 16:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:32:47 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-Tz7uCN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:32:47 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)) 16:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:32:47 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-f8Mb_l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:32:48 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)) 16:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:32:48 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-Fm1HNV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:32:48 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)) 16:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:32:48 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-3pNUZE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:32:48 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)) 16:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:32:48 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-Sk1g6i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:32:48 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)) 16:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:32:48 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-47D0LU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:32:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 16:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:32:48 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-W3WmXS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:32:48 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)) 16:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:32:48 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-wsEDRM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:32:48 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)) 16:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:32:48 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-r40JCd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:32:48 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)) 16:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:32:48 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-saxIGZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:32:48 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)) 16:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:32:48 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-4Fi9Y_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:32:49 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)) 16:32:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:32:49 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-a56tAa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:32:49 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)) 16:32:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:32:49 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-WcLe8L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:32:49 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)) 16:32:49 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)) 16:32:49 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)) 16:32:49 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)) 16:32:49 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)) 16:32:49 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)) 16:32:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:32:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:32:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:32:49 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 16:32:49 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 16:32:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:32:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:32:52 INFO - Downloading blessings-1.6.tar.gz 16:32:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:32:52 INFO - Installing collected packages: blessings 16:32:52 INFO - Running setup.py install for blessings 16:32:52 INFO - Successfully installed blessings 16:32:52 INFO - Cleaning up... 16:32:52 INFO - Return code: 0 16:32:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:32:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:32:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:32:52 INFO - Reading from file tmpfile_stdout 16:32:52 INFO - Current package versions: 16:32:52 INFO - blessings == 1.6 16:32:52 INFO - blobuploader == 1.2.4 16:32:52 INFO - docopt == 0.6.1 16:32:52 INFO - functools32 == 3.2.3-2 16:32:52 INFO - jsonschema == 2.5.1 16:32:52 INFO - manifestparser == 1.1 16:32:52 INFO - mozInstall == 1.12 16:32:52 INFO - mozcrash == 0.17 16:32:52 INFO - mozdebug == 0.1 16:32:52 INFO - mozdevice == 0.48 16:32:52 INFO - mozfile == 1.2 16:32:52 INFO - mozhttpd == 0.7 16:32:52 INFO - mozinfo == 0.9 16:32:52 INFO - mozleak == 0.1 16:32:52 INFO - mozlog == 3.4 16:32:52 INFO - moznetwork == 0.27 16:32:52 INFO - mozprocess == 0.23 16:32:52 INFO - mozprofile == 0.28 16:32:52 INFO - mozrunner == 6.12 16:32:52 INFO - mozscreenshot == 0.1 16:32:52 INFO - mozsystemmonitor == 0.3 16:32:52 INFO - moztest == 0.7 16:32:52 INFO - mozversion == 1.4 16:32:52 INFO - psutil == 3.1.1 16:32:52 INFO - requests == 1.2.3 16:32:52 INFO - wsgiref == 0.1.2 16:32:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:32: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')]} 16:32:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32: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')]} 16:32:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:52 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 0x1093e4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10935a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99f849d0f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091f8d30>, '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 0x10905edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091f0b20>, '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.Sz7xkMX6RZ/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.Ryat8Qt8Ko/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 16:32:52 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 16:32:52 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 16:32:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Sz7xkMX6RZ/Render', 16:32:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:32:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:32:52 INFO - 'HOME': '/Users/cltbld', 16:32:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:32:52 INFO - 'LOGNAME': 'cltbld', 16:32:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:52 INFO - 'MOZ_NO_REMOTE': '1', 16:32:52 INFO - 'NO_EM_RESTART': '1', 16:32:52 INFO - 'PAGER': '/bin/cat', 16:32:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:32:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:52 INFO - 'PWD': '/builds/slave/test', 16:32:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:32:52 INFO - 'SHELL': '/bin/bash', 16:32:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ryat8Qt8Ko/Listeners', 16:32:52 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 16:32:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:32:52 INFO - 'USER': 'cltbld', 16:32:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:32:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:32:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:52 INFO - 'XPC_FLAGS': '0x0', 16:32:52 INFO - 'XPC_SERVICE_NAME': '0', 16:32:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:32:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:52 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)) 16:32:52 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)) 16:32:52 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)) 16:32:52 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)) 16:32:52 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)) 16:32:52 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)) 16:32:52 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)) 16:32:52 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)) 16:32:52 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)) 16:32:52 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)) 16:32:52 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)) 16:32:52 INFO - Cleaning up... 16:32:53 INFO - Return code: 0 16:32:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:32: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')]} 16:32:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32: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')]} 16:32:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:53 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 0x1093e4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10935a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99f849d0f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091f8d30>, '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 0x10905edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091f0b20>, '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.Sz7xkMX6RZ/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.Ryat8Qt8Ko/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 16:32:53 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 16:32:53 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 16:32:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Sz7xkMX6RZ/Render', 16:32:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:32:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:32:53 INFO - 'HOME': '/Users/cltbld', 16:32:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:32:53 INFO - 'LOGNAME': 'cltbld', 16:32:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:53 INFO - 'MOZ_NO_REMOTE': '1', 16:32:53 INFO - 'NO_EM_RESTART': '1', 16:32:53 INFO - 'PAGER': '/bin/cat', 16:32:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:32:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:53 INFO - 'PWD': '/builds/slave/test', 16:32:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:32:53 INFO - 'SHELL': '/bin/bash', 16:32:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ryat8Qt8Ko/Listeners', 16:32:53 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 16:32:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:32:53 INFO - 'USER': 'cltbld', 16:32:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:32:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:32:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:53 INFO - 'XPC_FLAGS': '0x0', 16:32:53 INFO - 'XPC_SERVICE_NAME': '0', 16:32:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:32:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:32:53 INFO - Cleaning up... 16:32:53 INFO - Return code: 0 16:32:53 INFO - Running post-action listener: _resource_record_post_action 16:32:53 INFO - Running post-action listener: _start_resource_monitoring 16:32:53 INFO - Starting resource monitoring. 16:32:53 INFO - [mozharness: 2016-10-24 23:32:53.299507Z] Finished create-virtualenv step (success) 16:32:53 INFO - [mozharness: 2016-10-24 23:32:53.300285Z] Running install step. 16:32:53 INFO - Running pre-action listener: _resource_record_pre_action 16:32:53 INFO - Running main action method: install 16:32:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:32:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:32:53 INFO - Reading from file tmpfile_stdout 16:32:53 INFO - Detecting whether we're running mozinstall >=1.0... 16:32:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:32:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:32:53 INFO - Reading from file tmpfile_stdout 16:32:53 INFO - Output received: 16:32:53 INFO - Usage: mozinstall [options] installer 16:32:53 INFO - Options: 16:32:53 INFO - -h, --help show this help message and exit 16:32:53 INFO - -d DEST, --destination=DEST 16:32:53 INFO - Directory to install application into. [default: 16:32:53 INFO - "/builds/slave/test"] 16:32:53 INFO - --app=APP Application being installed. [default: firefox] 16:32:53 INFO - mkdir: /builds/slave/test/build/application 16:32:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 16:32:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 16:33:25 INFO - Reading from file tmpfile_stdout 16:33:25 INFO - Output received: 16:33:25 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 16:33:25 INFO - Running post-action listener: _resource_record_post_action 16:33:25 INFO - [mozharness: 2016-10-24 23:33:25.791662Z] Finished install step (success) 16:33:25 INFO - [mozharness: 2016-10-24 23:33:25.791851Z] Running run-tests step. 16:33:25 INFO - Running pre-action listener: _resource_record_pre_action 16:33:25 INFO - Running main action method: run_tests 16:33:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:33:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:33:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:33:25 INFO - 2016-10-24 16:33:25.806 screenresolution[1642:7772] starting screenresolution argv=screenresolution get 16:33:25 INFO - 2016-10-24 16:33:25.808 screenresolution[1642:7772] Display 0: 1600x1200x32@60 16:33:25 INFO - 2016-10-24 16:33:25.814 screenresolution[1643:7774] starting screenresolution argv=screenresolution list 16:33:25 INFO - Available Modes on Display 0 16:33:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:33:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:33:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:33:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:33:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:33:26 INFO - Intel Iris: 16:33:26 INFO - Chipset Model: Intel Iris 16:33:26 INFO - Type: GPU 16:33:26 INFO - Bus: Built-In 16:33:26 INFO - VRAM (Dynamic, Max): 1536 MB 16:33:26 INFO - Vendor: Intel (0x8086) 16:33:26 INFO - Device ID: 0x0a2e 16:33:26 INFO - Revision ID: 0x0009 16:33:26 INFO - Displays: 16:33:26 INFO - Display: 16:33:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:33:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:33:26 INFO - Display Serial Number: 1600x1200 60 16:33:26 INFO - Main Display: Yes 16:33:26 INFO - Mirror: Off 16:33:26 INFO - Online: Yes 16:33:26 INFO - Rotation: Supported 16:33:26 INFO - Return code: 0 16:33:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:33:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:33:26 INFO - Python 2.7.3 16:33:26 INFO - Return code: 0 16:33:26 INFO - grabbing minidump binary from tooltool 16:33: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')]} 16:33:26 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 0x1091f8d30>, '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 0x10905edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091f0b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:33:26 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 16:33:26 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 16:33:26 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 16:33:26 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:33:26 INFO - Return code: 0 16:33:26 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:33:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:33:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:33:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:33:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:33:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WC1P55XORRylbGVOut0Z5A/artifacts/public/build/firefox-51.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0320', '--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 16:33:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/WC1P55XORRylbGVOut0Z5A/artifacts/public/build/firefox-51.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0320 --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 16:33:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Sz7xkMX6RZ/Render', 16:33:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:33:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:33:26 INFO - 'HOME': '/Users/cltbld', 16:33:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:33:26 INFO - 'LOGNAME': 'cltbld', 16:33:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:33:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:33:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:33:26 INFO - 'MOZ_NO_REMOTE': '1', 16:33:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:33:26 INFO - 'NO_EM_RESTART': '1', 16:33:26 INFO - 'PAGER': '/bin/cat', 16:33:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:33:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:33:26 INFO - 'PWD': '/builds/slave/test', 16:33:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:33:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:33:26 INFO - 'SHELL': '/bin/bash', 16:33:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ryat8Qt8Ko/Listeners', 16:33:26 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 16:33:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:33:26 INFO - 'USER': 'cltbld', 16:33:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:33:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:33:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:33:26 INFO - 'XPC_FLAGS': '0x0', 16:33:26 INFO - 'XPC_SERVICE_NAME': '0', 16:33:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:33:26 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WC1P55XORRylbGVOut0Z5A/artifacts/public/build/firefox-51.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0320', '--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 16:33:26 INFO - mozversion application_buildid: 20161024140622 16:33:26 INFO - mozversion application_changeset: bf9235ab0289e3fc88cc0fdba9f02ba7983aa695 16:33:26 INFO - mozversion application_display_name: FirefoxDeveloperEdition 16:33:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:33:26 INFO - mozversion application_name: Firefox 16:33:26 INFO - mozversion application_remotingname: firefox-dev 16:33:26 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 16:33:26 INFO - mozversion application_vendor: Mozilla 16:33:26 INFO - mozversion application_version: 51.0a2 16:33:26 INFO - mozversion platform_buildid: 20161024140622 16:33:26 INFO - mozversion platform_changeset: bf9235ab0289e3fc88cc0fdba9f02ba7983aa695 16:33:26 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 16:33:26 INFO - mozversion platform_version: 51.0a2 16:33:26 INFO - using testdate: 1477352006 16:33:26 INFO - actual date: 1477352006 16:33:26 INFO - starting webserver on 'localhost:49278' 16:33:26 INFO - SUITE-START | Running 2 tests 16:33:26 INFO - TEST-START | dromaeo_css 16:33:26 INFO - operating with platform_type : mac_ 16:33:26 INFO - Initialising browser for dromaeo_css test... 16:33:26 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/tmp13g8jv/profile http://localhost:49278/getInfo.html) 16:33:28 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 16:33:28 INFO - PROCESS | 1653 | colorDepth:24 16:33:28 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 16:33:28 INFO - PROCESS | 1653 | __metrics 16:33:28 INFO - PROCESS | 1653 | 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" 16:33:28 INFO - PROCESS | 1653 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:33:29 INFO - TEST-INFO | 1653: exit 0 16:33:29 INFO - Browser initialized. 16:33:29 INFO - Running cycle 1/1 for dromaeo_css test... 16:33:29 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/tmp13g8jv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:33:41 INFO - PROCESS | 1654 | RSS: Main: 219987968 16:33:41 INFO - PROCESS | 1654 | 16:34:32 INFO - PROCESS | 1654 | 1477352072727 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] 16:36:44 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49278/tests/dromaeo/cssquery-ext.html) 16:36:45 INFO - PROCESS | 1654 | RSS: Main: 360910848 16:36:45 INFO - PROCESS | 1654 | 16:39:48 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-ext.html (next: http://localhost:49278/tests/dromaeo/cssquery-jquery.html) 16:39:49 INFO - PROCESS | 1654 | RSS: Main: 360284160 16:39:49 INFO - PROCESS | 1654 | 16:42:51 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49278/tests/dromaeo/cssquery-mootools.html) 16:42:52 INFO - PROCESS | 1654 | RSS: Main: 324988928 16:42:52 INFO - PROCESS | 1654 | 16:45:54 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49278/tests/dromaeo/cssquery-prototype.html) 16:45:55 INFO - PROCESS | 1654 | RSS: Main: 313020416 16:45:55 INFO - PROCESS | 1654 | 16:48:58 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49278/tests/dromaeo/cssquery-yui.html) 16:48:59 INFO - PROCESS | 1654 | RSS: Main: 310042624 16:48:59 INFO - PROCESS | 1654 | 16:52:02 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-yui.html (next: http://localhost:49278/tests/dromaeo/cssquery-dojo.html) 16:52:02 INFO - PROCESS | 1654 | RSS: Main: 398360576 16:52:02 INFO - PROCESS | 1654 | 16:52:02 INFO - PROCESS | 1654 | __start_tp_report 16:52:02 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 16:52:02 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 16:52:02 INFO - PROCESS | 1654 | |i|pagename|runs| 16:52:02 INFO - PROCESS | 1654 | |0;dojo.html;3285.605727312723;3328.534005239266;3363;3384.0862966998925;3393.864245430183;5447.9182812257795;5682.062459694151;5700.401457846918;5716.39977802331;5723.313202415706;4550.704204226723;5681.57388195886;5759.4816466518005;5760.135979603062;5768.884622307552;5192.818251361203;5200.297959775429;5225.764840582183;5238.842834714946;5251.658642188265;5165.276861239429;5170.9224361634415;5176.896462070775;5318.228856815759;5345.984262990039;5211.114110601188;5247.895042099175;5270.446603106676;5292.153255479124;5318.813841515548;5199.66202196856;5209.1665333546825;5228.660137091077;5260.605454590921;5278.551323137538;5097.108006098955;5181;5190.818321358772;5223.73881305937;5267.683938963636;5523.696196709179;5599.888002239933;5599.8880022399735;5607.411221821691;5700.429957004308;6201.069839524062;6257.9687101564205;6272.247330320391;6278.748850045993;6290.276618188921;6266.028765541303;6325.557210995232;6326.715297811609;6331.2719037310835;6355.777547785805;5442.482964118402;5448.618596698193;5478.45215478449;5791.986402379567;5795.362510123883;6221.688915554204;6273.498120150377;6274.152989346379;6276.152719382825;6297.811065668037;6158.938410615837;6160.476359509402;6162.352952939877;6168.228971378578;6171.043488259327;6139.692169977852;6249.406306400884;6296.5592408531875;6314.652694101868;6328.11406403104;6079.08813677943;6107.378879269592;6112.633242005494;6139.815805525842;6201;6213.751449942042;6246.031865060922;6253.593516421509;6284.308726040132;6284.465820405317;4921.9015619688125;4949.82675606352;4950.2822090796635;4966.15575352191;4989.226669866177;4329.480462344539;4347.673924455616;4410.977945110318;4411.779411029435;4453.42105526286;5811.941880581225;5821.09772985188;5887.793927212527;5915.319738230072;5927.644341339534;6225.221847269091;6259.843503912439;6268.4671802896355;6272.968635156795;6281.748730050838;5646.85882852932;5649.519790817826;5737.3975732548315;5875.529957603381;5881.823545293648;4595.793189306422;5331.280277162611;5379.246905433242;5391.2182733504205;5412.83761487156;6280.340564240872;6331.841703957346;6361.045843123568;6370.904436433457;6377.74489020434;6258.155149055002;6282.528810339151;6286.434220919956;6294.527910406646;6301.6219026858535;6220.315765265907;6265.498760099273;6274.152989346562;6286.899792536196;6314.936850631619;6180.165677633642;6195.783147589813;6203.193584833943;6206.06908963641;6230.937690623217;6181.320054794059;6183.659898705434;6241.843953901189;6250;6265.96867015662;5940.287165540163;6011.308699499438;6140.232470941132;6158.784442544489;6173.197484327188;5446.264754258283;5609.074502707008;5770.798022069207;5799.101139323411;5849.005669036189;5656.6888821115235;5664.971675141598;5672.914906276492;5675.602707810579;5693.800716974886;5743.10981797822;5781.682007489629;5787.189793428839;5806.216160818235;5949.137375080676;5833.095870140135;5875.73559189846;5903.822885313448;5949.25634295713;5967.6419414835245 16:52:02 INFO - PROCESS | 1654 | |1;ext.html;455.32157085941947;484.64862974343595;493.2010143567421;498.18297991294304;542.3464725006368;30788.460576971145;41007.58992410079;42060.94847628807;42146;44582;43511.34732979008;43845.9038524037;43858.780706096506;43963.56036439635;44014.11971760563;8762.912370876305;9862.16171625411;10051.798964020716;10052.246081543877;10055.24585656075;8982;12069.758604827899;12343.13598048137;12349.814752778715;12538.623841284776;10221.386716797017;12402.573704024016;12429.937850310782;12436.191647542926;12462.065345099154;12358;12421.44103515335;12442;12442.004639628807;12449.875501244962;12104.394780260951;12132.818007729891;12141.817872731915;12152.57465988686;12167.087468439902;39850.40224396636;40204.39693404601;40243.7987810062;40364.99087522806;40424.191516169514;3334.2664613784927;3468.167639766438;3589.1565482111682;3905.3360928641914;3987.641112299878;29145.56281655777;29879.80480780766;30474.085777426713;31806.840965795254;31913.84043079793;3091.3971775503787;3117.407692538433;3274.5088236764436;3300.207950091961;3305.2232725309773;514562;515595;515922;516174;517014.41492792673;49227.753861231184;60416.69791651014;61009;61044.38955610476;61075.38924610786;58605.70697146572;58629.827403451694;58698.82602347929;58718;58840;16727.247273872705;16948.74576881348;16961.982281063174;16973.40593079261;16994.660106797794;11773.352465614302;11986.760264794655;11993.760124797454;13164;13247.867521324664;13016.349182540835;14063.60918643833;14592.562223133324;16244.675106497803;16581.419650312422;5405.972970135124;6353.174087368612;6411.326810684905;6419.1334169887405;6514.771982980573;880.8150288439372;959.8704174936433;973.9870534643891;1048.6853943816825;1105.1048650593045;28853.422931541252;28892;29350.853245733633;29526.852365738036;29646.258843529085;1042.781015986636;1047.3925123428394;1112;1138.3511398502774;1141.7259857634108;14834.96877787698;15168.620784480476;15492.922535387477;15593.376264949502;15769.132697701507;665578;670727;671851;672029;673849;58221;58250.70874645685;58395.12407313893;58497;58547.12179317314;1210.1831263896834;1256.472281641731;1356.2947267421084;1358.3615700620692;1391.24177323359;29674.851625741732;31230.843845780626;31906.52140217852;31938.68061319357;33096.50355244721;49317.50682493272;49712;50040.99918001547;50050.99898001947;50056.748581285756;559425.2028739831;560520;563260.1836990789;563404;663958;24994.125205617715;25990;26029.39706029382;26053.218403447925;26058.869705651352;37313.835635607924;45795.48124853165;45863;46687.06625867532;46815.29777053279;38154;38408.615913840506;38948;40069.59930400775;40109.79945100256;38280.617193827704;38313.616863831005;38808.029799255244;39742.40386394149;40037;37041.62958370382;38490.615093849825;39218.41172382359;39404;40791.7960410196;31280;35196.64803352036;35416.811998604404;35537;35771.64228357683;24446.877765611058;25872.223833285032;25956.480870382107;26216.082437115372;26525 16:52:02 INFO - PROCESS | 1654 | |2;jquery.html;351.02765340008165;372.86017743346247;373.99065023374413;383.63746259784506;405.1168452772954;15502.612434689125;15530.922345388271;20409.591808163845;21349.466263343424;22252.221172258975;20086.196552137935;20791.584168316625;20989.055492502837;21018.369448916517;21095.472613184676;5319.441458646835;5605.859853503654;5611.0741727614895;5634.4365563443735;5638.661680299195;5491.515608385034;5563.749631266603;6021.157038014681;6158.722857471402;6183.845403864894;6158.445739883409;6162.260528736558;6163;6165.229346331708;6169.19800425944;4709.481956984729;5591.860203494904;5604.103343465067;5612.382637909826;5720.170575266584;5252.894942101137;5447.863803404907;5456.590755693339;5463.808766693147;5464.28964234647;25625.23124306274;26026.609600856;26127.477450450886;26348.73651263482;26529.93880244788;2091.7699053104147;2240.820734341265;2564.743525647439;3031.348260124084;3057.4038062577806;9993.750156246151;10403.063724264777;10894.074003709695;15828.287727052195;16327.428540001043;1570.5994971282364;1622.8863979521439;1828.5520047588293;1842.1618163735468;1949.5475870476444;124708;129635.71895489619;135404;136477.31761341228;136823;9827.82066152052;10428.426436546062;11247.775044499063;11294;11586.536538538365;13682.110662806886;13842.723145537033;26866.32834179122;35453.113672158404;35797;9962.800743985224;10799.272116461329;11032.227744058;11360.431978401048;11710;8840.425372350777;9087.591058402388;9185.402948808307;9217.76955576116;9530.666426675032;9594.808103837884;9709;9815.852762208573;9947.701568952943;9968.551415186334;3698.204885949534;3757.8204641307975;3768.8304026318333;3770.67949224319;3809.7523660961947;352.2707994451461;389.974651647642;402.22170100854356;410.4848415238916;815.3640160674684;11462.713432164262;11769.470373833197;11791.705207369885;11874.406279685982;12184.329861857506;433.3175248982865;434.3961892968776;441.60255769806787;451.21263395374626;465.71358614452544;5419.945800542023;5990.580659353804;6045.697715114226;6138.324784273726;6199.473044791243;611587;625802;627237;627708;632393;146159;150345;151221.2438937798;151792;156223;434.20757118259303;439.2159994410027;439.3585365366537;439.3695047606695;439.42654835440055;11066.557337706723;11112.611058612909;11134.888651113386;11446.255993360404;11465.713357166138;49273.50726492689;51701.741491292305;52752.4174274757;52784.47215527796;54751.72624137013;64010;80463.59768201121;80687.78968315598;84695;87313;8076.27313541784;8138.389620778584;8196.344292456708;8316;8592.441491303045;10315.896841031794;12140.210886292363;12309.830566096207;12763.106582539132;13015.739685206054;5437.619366644297;5544.085225937836;10833.724917767042;11185.937335953071;11256.099512038854;9548.901876284317;10732.517036733521;11250.324980501196;11407.315561066363;11782.469788859693;11265.26775759573;11310.208285419942;11491.080713543064;11575.363355015555;11775.116866234895;7008.439324854102;7452.813679658052;7506.699732010658;8125.79685507867;8457.915420845713;11203.047740942111;11369.886301136883;11381.032612228053;11507.424628768695;11787.941060294643 16:52:02 INFO - PROCESS | 1654 | |3;mootools.html;1342.0069148829868;1506.179132372857;1523.6571771351444;1702.9403970861022;1867.6544839204757;7915.287624113828;8414.200650938159;8750.562471876412;8950.910490895089;9009.369344145913;8889.955550222256;8973.91026089739;8977.19205271527;8978.91021089789;8991.775205619862;1981.1580078466666;2358.69336986192;2389.2712722619594;2391.8086553075714;2392.055138220402;2356.752540983189;2380.321608341623;2409.734929157791;2420.576399130145;2427.8057755379527;2364.6216605343147;2371.988140059306;2380.773826486481;2395.2933884504114;2408.397900524869;2355.057976809273;2370.6562548430466;2382.213869423086;2387.6299173628117;2388.5222955408813;2332.1720789119786;2363.3855197648736;2368.988155059231;2372.454335502844;2372.833901626887;8663.220310172052;8894.59974301158;8945.194932456045;9037.412568183046;9069.863952040725;5328.6802791832615;5490.423505531941;5491;5496.780128794843;5499.587530935156;3632.7820330780237;3661.743677942545;3699.223163135745;3732.1789206374824;3735.1595890924596;1092.2354351953663;1107.141964977141;1114;1114.9944250278777;1115.709915421988;11874.703132421671;11954.521819127225;11998.760024799456;12008.939955300255;12020.939895300555;3655.7258205634153;3916.9608303917166;4523.819047238139;4691.929621055687;4711.316859055486;9245.260379169651;9245.445273283625;9248.583813728399;9251.398659087137;9261.768455788659;5305.124654431976;5305.787768489217;5309.964556911365;5310.654807437504;5322.33470816148;4446.1330040642415;4471.28459446487;4644.930326045112;4646.86059418218;4995.400551933719;3794.829232684536;3831.8084095795098;3862.343401621731;3907.6092390760696;3918.294706952719;992.8560358747941;1188.5245901639241;1188.5245901639414;1202.60314096348;1204.0668481926577;908.6683360573343;913.7923369868568;919.7102912582515;940;944.6363150187227;5741.45456181662;5864.091065884794;5924.259467566554;6117.327094019654;6200.503959683215;356.98750543730847;380.739193652349;417.912238429927;440.78842155765415;462.93981782368076;3904.160605469781;3942.4677668514955;3954.426608141861;4046.858359957387;4076.267586275821;10636.893631063745;10727.141828653845;11126.38804865724;11143.442827858575;11192.608258711069;10868;11071.5571377144;11076.556937722562;11082.556697732;11096.55613775456;8265.669373225124;8373.790655233668;8411.158884111539;8498.277646400125;8607.956960215284;5751.539876809761;5966.492848107786;5967.9701601491715;6036.426539478743;6107.053406721965;10006.949965250127;10164.644237451654;10246.948765256126;10255.538500767334;10280.331778434676;9540.23678105736;9935.900640993497;10348.637797677045;10456.111230545488;10655.200859935381;6815.522913396015;6927.68825402848;7691.115521714851;7695.730649427243;7809;8267.586620668822;8294.502329860228;8375.41372103947;8381.329493640616;8463.28062114727;7490.715331823846;7500.437467189873;7515.6993720250575;7533.096028476618;7566.546007239584;7343.559386436831;7350.264973502607;7390.9260907390235;7437.962810185914;7518.887216691864;5265.710385928811;6994.650267486503;7012.789616311519;7080.398166155935;7088.0076789248515;4813.879653008703;4869.245266983623;4882.633802464758;5004.974975125101;5103.489651034867;4932.950670493249;4944.48082951285;4961.181405068268;4997.675151115166;5053.67151135175 16:52:02 INFO - PROCESS | 1654 | |4;prototype.html;297.784106522771;302.6973026973027;310.9129443755748;312.9624445066592;313.4608473425707;4289.528151903288;5271.604629652777;5297.602679799012;5338.973305133469;5386.542143917772;4603.976980115095;5032.698038117706;5159.807081990149;5179.378474583046;5216.452272511379;3585.910352241195;3800.638939300762;4059.6955228357842;4083.836646534135;4104.8563300284495;3398.660133986594;3722.7394082414244;4069.674426045909;4076.694247931402;4079.2045551117544;3869.8839034829;4065.512138543364;4065.6747460203114;4092.6725861930972;4093.219449539445;3384.526166336715;4053.108316170438;4058.9446743846515;4074.8166332515107;4098.364753463203;3527.5766907971206;3550.8579656813695;3560.7211384713546;3563;3566.554180727409;4035.3543433050572;5165.405978312505;5177.663451875616;5180;5182.792688292501;3858.884233473;3975.105601239698;4088.7342322748927;4625.421822272216;4630.236011058171;5065.518775716301;5464.26232458617;5535.529479994206;5548.306461692288;5554.805581804618;3875.205582855508;3895.8831235062994;3902.7268091233627;3916.941245881314;3985.5815139410233;5009.774560144766;5631.774729010835;5773.826785196451;5798.82603521895;5835.824925252249;3931.5282166140246;3942.211557688474;3959.5248570171766;3966.940495892564;3970.68234541236;4031.576684448091;4037.818298176589;4045.352743561075;4046.8785936421364;4058.559211479882;3698.593154752975;4105.445764821711;4597.310403439511;4662.393888794435;4733.952660473351;4709.882252943636;5588.189712491666;5606.206014075457;5616.410276920865;5680.801171958961;1161.7908776420325;1314.2574445438297;1342.2952949701485;1376.167418711685;1378.9448422063006;769.3844924060729;857.498363457372;888.6667499687617;895.2211575928853;937.9765505862408;4710.940038491381;5192.324997750268;5195.454477279907;5211.062008838369;5253.711045892513;4722.669413141047;5494.560435165177;5724.942750572524;5746.396628353963;5806.419358064244;4928.722399604223;5100.260462232955;5184.481551844785;5232.450592687713;5233.607479439057;2540.7713305802567;2968.1449094316054;2999.9400011999637;3013.789034767589;3032.3935212957495;5316.946830531722;5337.679739215582;5454.563634909197;5543;5951.869144862462;4657.930131048037;4797.56821886032;5733.971330143323;5791.681457519792;5797.710114494258;5847.473727364558;5881.235439392852;5885.352611212677;5893.086571581326;5895.233619629421;4253.893652658709;5309;5678.914816277676;5694.629849059798;5816.621919575214;5144.717040562805;5374.925014997095;5566.22072909801;5587.69267690281;5616.157576363578;5008.649394542347;5504.394516603251;5870.7945221917025;5873.913326034697;5889.793857214977;3753.9624603754705;3813.7521061130938;3813.985944275554;3871.438641397038;3954.3079961006133;4799.400074990626;5589.608727389043;5604.747786349705;5608.383077861514;5615.438456154352;5056.342675452168;5107.642465027412;5534.446555344433;5540.94984855606;5564.944350556442;5284.894302114013;5454.945450545444;5487.259220005249;5517.889642207214;5550.5282051026115;4917.582005529427;5136.203888397305;5378.542823860016;5381.704006279692;5410.8637186191345;4503.2212276151395;5352.812651557177;5410.026195284887;5416;5594.776208951596;4119.97940010298;4497.797599108113;4623.052274283831;4688.6717929745955;5411.512963833116 16:52:02 INFO - PROCESS | 1654 | |5;yui.html;715.9987143669493;780.0795061827049;790.383500869322;798.2456578533285;801.0907619851466;17308.74036889446;18001.90999045006;18014.549636259115;18042.368517101902;18497.445076647688;15875.444359447421;16055.438059667884;16853.073080980572;16865.83134168658;16866.90365126266;674.7840690978888;695.4436450839333;837.9790505237372;861.0313397427893;865.3336930563462;690.9792706218797;823.2097186700775;837.1419295222385;846.124261389462;852.3479538153317;906.4380084347681;911.5260064766318;913.899471058183;917.1837065012111;921.138735282513;699.4614147106699;907.5598334807613;908.2733812949614;910.9134632209963;912.4662072687486;530.864629519474;641.8908785506475;655.4592461219513;659.7855696898479;661.503872095928;15035.54893353201;15470.767938480933;15538.61153471161;15554.766678499831;15555;12462.940650044757;13858.4456621735;14105.717885642332;14370.712585748226;14702.4854130106;11103.44482775858;11925.582604608884;12671.87600648113;13031.543895963596;13403.86596134036;4332.458442694663;4662;4687.929681054787;4853.538913803183;4872.731999740013;89570.55214723885;125659.37170314182;126298.73701262962;128583;129310.41379172458;11054.115670746482;11357.97778199966;11451.25566838153;11550.653480395602;11565.76868462626;22457.43856403603;22584.774152258597;22606.547869042857;22796.772032279467;22802.42993925165;7685.193054729173;7784.494007889469;7862.449628525948;7900.881486777703;8221.958890205511;4289.978550107229;4343.2399330117105;4344.370066340363;4347.56524347569;4561.087782443525;3571.5535558055244;3679.7240206984584;3682.0978860178893;3684.1342284563107;3686.3733165361955;642.8971364581645;647.685872351909;662.3872917551342;674.780696273713;678.2708588267632;552.809280798124;610.5817515002237;623.2894500269696;636.4939872801134;640.7308930249307;8527.232549070613;8928;9130.223930965942;9278.443293402417;9382.624695012122;518.3831240823483;535.8713908661971;552.7181137619763;583.585654185533;598.2611474828583;4549.545045495424;4707.576318131385;4722.669413141047;4730.645201609893;4735.644826638016;102208;109690;153159;157080;158569.5215906638;121706;126660.73339266673;130910;131648.3417582906;132368;113062;116103.41948290373;116845.41577292059;119680.20479692986;121514;8482.708897894387;8889.288856891304;8916.687915922912;9137.954310228672;9342.906570934203;59375.70312148412;61423;61700;63652.68173659102;63898.68050659717;79442;91778.54110729403;95325;96723;97296;10393.480325983519;11567.421628918351;11633.418329083343;11732.472038758448;11780.646580602595;10760.3543787373;12842.391088912836;12955.417006234553;12972.221666700028;13154.34228288601;11352.432378380883;12000.099992500423;12089.69775755578;12294.87705122973;13845.653858653212;13044.478220871439;13899.23554204488;13937;14483.782743259071;14484.710305793615;13606.795898061338;13665.521706740217;14088;14225.00424970242;14278.357473913491;11192.888071119185;11440.94279528597;11813.704657383634;11906.76186476283;12066.818997714865;11443.48504317291;11561.393841006133;11699.766004680028;11719.472623731783;11825.645230643095 16:52:02 INFO - PROCESS | 1654 | __end_tp_report 16:52:02 INFO - PROCESS | 1654 | __start_cc_report 16:52:02 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,1298 16:52:02 INFO - PROCESS | 1654 | __end_cc_report 16:52:02 INFO - PROCESS | 1654 | __startTimestamp1477353122489__endTimestamp 16:52:02 INFO - PROCESS | 1654 | 16:52:02 INFO - PROCESS | 1654 | ------- Summary: start ------- 16:52:02 INFO - PROCESS | 1654 | Number of tests: 6 16:52:02 INFO - PROCESS | 1654 | 16:52:02 INFO - PROCESS | 1654 | [#0] dojo.html Cycles:165 Average:5711.68 Median:5821.10 stddev:649.64 (11.2%) stddev-sans-first:623.14 16:52:02 INFO - PROCESS | 1654 | Values: 3285.6 3328.5 3363.0 3384.1 3393.9 5447.9 5682.1 5700.4 5716.4 5723.3 4550.7 5681.6 5759.5 5760.1 5768.9 5192.8 5200.3 5225.8 5238.8 5251.7 5165.3 5170.9 5176.9 5318.2 5346.0 5211.1 5247.9 5270.4 5292.2 5318.8 5199.7 5209.2 5228.7 5260.6 5278.6 5097.1 5181.0 5190.8 5223.7 5267.7 5523.7 5599.9 5599.9 5607.4 5700.4 6201.1 6258.0 6272.2 6278.7 6290.3 6266.0 6325.6 6326.7 6331.3 6355.8 5442.5 5448.6 5478.5 5792.0 5795.4 6221.7 6273.5 6274.2 6276.2 6297.8 6158.9 6160.5 6162.4 6168.2 6171.0 6139.7 6249.4 6296.6 6314.7 6328.1 6079.1 6107.4 6112.6 6139.8 6201.0 6213.8 6246.0 6253.6 6284.3 6284.5 4921.9 4949.8 4950.3 4966.2 4989.2 4329.5 4347.7 4411.0 4411.8 4453.4 5811.9 5821.1 5887.8 5915.3 5927.6 6225.2 6259.8 6268.5 6273.0 6281.7 5646.9 5649.5 5737.4 5875.5 5881.8 4595.8 5331.3 5379.2 5391.2 5412.8 6280.3 6331.8 6361.0 6370.9 6377.7 6258.2 6282.5 6286.4 6294.5 6301.6 6220.3 6265.5 6274.2 6286.9 6314.9 6180.2 6195.8 6203.2 6206.1 6230.9 6181.3 6183.7 6241.8 6250.0 6266.0 5940.3 6011.3 6140.2 6158.8 6173.2 5446.3 5609.1 5770.8 5799.1 5849.0 5656.7 5665.0 5672.9 5675.6 5693.8 5743.1 5781.7 5787.2 5806.2 5949.1 5833.1 5875.7 5903.8 5949.3 5967.6 16:52:02 INFO - PROCESS | 1654 | 16:52:02 INFO - PROCESS | 1654 | [#1] ext.html Cycles:180 Average:72011.11 Median:35654.32 stddev:158683.12 (445.1%) stddev-sans-first:159037.32 16:52:02 INFO - PROCESS | 1654 | Values: 455.3 484.6 493.2 498.2 542.3 30788.5 41007.6 42060.9 42146.0 44582.0 43511.3 43845.9 43858.8 43963.6 44014.1 8762.9 9862.2 10051.8 10052.2 10055.2 8982.0 12069.8 12343.1 12349.8 12538.6 10221.4 12402.6 12429.9 12436.2 12462.1 12358.0 12421.4 12442.0 12442.0 12449.9 12104.4 12132.8 12141.8 12152.6 12167.1 39850.4 40204.4 40243.8 40365.0 40424.2 3334.3 3468.2 3589.2 3905.3 3987.6 29145.6 29879.8 30474.1 31806.8 31913.8 3091.4 3117.4 3274.5 3300.2 3305.2 514562.0 515595.0 515922.0 516174.0 517014.4 49227.8 60416.7 61009.0 61044.4 61075.4 58605.7 58629.8 58698.8 58718.0 58840.0 16727.2 16948.7 16962.0 16973.4 16994.7 11773.4 11986.8 11993.8 13164.0 13247.9 13016.3 14063.6 14592.6 16244.7 16581.4 5406.0 6353.2 6411.3 6419.1 6514.8 880.8 959.9 974.0 1048.7 1105.1 28853.4 28892.0 29350.9 29526.9 29646.3 1042.8 1047.4 1112.0 1138.4 1141.7 14835.0 15168.6 15492.9 15593.4 15769.1 665578.0 670727.0 671851.0 672029.0 673849.0 58221.0 58250.7 58395.1 58497.0 58547.1 1210.2 1256.5 1356.3 1358.4 1391.2 29674.9 31230.8 31906.5 31938.7 33096.5 49317.5 49712.0 50041.0 50051.0 50056.7 559425.2 560520.0 563260.2 563404.0 663958.0 24994.1 25990.0 26029.4 26053.2 26058.9 37313.8 45795.5 45863.0 46687.1 46815.3 38154.0 38408.6 38948.0 40069.6 40109.8 38280.6 38313.6 38808.0 39742.4 40037.0 37041.6 38490.6 39218.4 39404.0 40791.8 31280.0 35196.6 35416.8 35537.0 35771.6 24446.9 25872.2 25956.5 26216.1 26525.0 16:52:02 INFO - PROCESS | 1654 | 16:52:02 INFO - PROCESS | 1654 | [#2] jquery.html Cycles:180 Average:36753.78 Median:3769.75 stddev:105118.36 (2788.5%) stddev-sans-first:105377.71 16:52:02 INFO - PROCESS | 1654 | Values: 351.0 372.9 374.0 383.6 405.1 15502.6 15530.9 20409.6 21349.5 22252.2 20086.2 20791.6 20989.1 21018.4 21095.5 5319.4 5605.9 5611.1 5634.4 5638.7 5491.5 5563.7 6021.2 6158.7 6183.8 6158.4 6162.3 6163.0 6165.2 6169.2 4709.5 5591.9 5604.1 5612.4 5720.2 5252.9 5447.9 5456.6 5463.8 5464.3 25625.2 26026.6 26127.5 26348.7 26529.9 2091.8 2240.8 2564.7 3031.3 3057.4 9993.8 10403.1 10894.1 15828.3 16327.4 1570.6 1622.9 1828.6 1842.2 1949.5 124708.0 129635.7 135404.0 136477.3 136823.0 9827.8 10428.4 11247.8 11294.0 11586.5 13682.1 13842.7 26866.3 35453.1 35797.0 9962.8 10799.3 11032.2 11360.4 11710.0 8840.4 9087.6 9185.4 9217.8 9530.7 9594.8 9709.0 9815.9 9947.7 9968.6 3698.2 3757.8 3768.8 3770.7 3809.8 352.3 390.0 402.2 410.5 815.4 11462.7 11769.5 11791.7 11874.4 12184.3 433.3 434.4 441.6 451.2 465.7 5419.9 5990.6 6045.7 6138.3 6199.5 611587.0 625802.0 627237.0 627708.0 632393.0 146159.0 150345.0 151221.2 151792.0 156223.0 434.2 439.2 439.4 439.4 439.4 11066.6 11112.6 11134.9 11446.3 11465.7 49273.5 51701.7 52752.4 52784.5 54751.7 64010.0 80463.6 80687.8 84695.0 87313.0 8076.3 8138.4 8196.3 8316.0 8592.4 10315.9 12140.2 12309.8 12763.1 13015.7 5437.6 5544.1 10833.7 11185.9 11256.1 9548.9 10732.5 11250.3 11407.3 11782.5 11265.3 11310.2 11491.1 11575.4 11775.1 7008.4 7452.8 7506.7 8125.8 8457.9 11203.0 11369.9 11381.0 11507.4 11787.9 16:52:02 INFO - PROCESS | 1654 | 16:52:02 INFO - PROCESS | 1654 | [#3] mootools.html Cycles:180 Average:5722.40 Median:4762.60 stddev:3274.72 (68.8%) stddev-sans-first:3267.35 16:52:02 INFO - PROCESS | 1654 | Values: 1342.0 1506.2 1523.7 1702.9 1867.7 7915.3 8414.2 8750.6 8950.9 9009.4 8890.0 8973.9 8977.2 8978.9 8991.8 1981.2 2358.7 2389.3 2391.8 2392.1 2356.8 2380.3 2409.7 2420.6 2427.8 2364.6 2372.0 2380.8 2395.3 2408.4 2355.1 2370.7 2382.2 2387.6 2388.5 2332.2 2363.4 2369.0 2372.5 2372.8 8663.2 8894.6 8945.2 9037.4 9069.9 5328.7 5490.4 5491.0 5496.8 5499.6 3632.8 3661.7 3699.2 3732.2 3735.2 1092.2 1107.1 1114.0 1115.0 1115.7 11874.7 11954.5 11998.8 12008.9 12020.9 3655.7 3917.0 4523.8 4691.9 4711.3 9245.3 9245.4 9248.6 9251.4 9261.8 5305.1 5305.8 5310.0 5310.7 5322.3 4446.1 4471.3 4644.9 4646.9 4995.4 3794.8 3831.8 3862.3 3907.6 3918.3 992.9 1188.5 1188.5 1202.6 1204.1 908.7 913.8 919.7 940.0 944.6 5741.5 5864.1 5924.3 6117.3 6200.5 357.0 380.7 417.9 440.8 462.9 3904.2 3942.5 3954.4 4046.9 4076.3 10636.9 10727.1 11126.4 11143.4 11192.6 10868.0 11071.6 11076.6 11082.6 11096.6 8265.7 8373.8 8411.2 8498.3 8608.0 5751.5 5966.5 5968.0 6036.4 6107.1 10006.9 10164.6 10246.9 10255.5 10280.3 9540.2 9935.9 10348.6 10456.1 10655.2 6815.5 6927.7 7691.1 7695.7 7809.0 8267.6 8294.5 8375.4 8381.3 8463.3 7490.7 7500.4 7515.7 7533.1 7566.5 7343.6 7350.3 7390.9 7438.0 7518.9 5265.7 6994.7 7012.8 7080.4 7088.0 4813.9 4869.2 4882.6 5005.0 5103.5 4933.0 4944.5 4961.2 4997.7 5053.7 16:52:02 INFO - PROCESS | 1654 | 16:52:02 INFO - PROCESS | 1654 | [#4] prototype.html Cycles:180 Average:4473.25 Median:5060.93 stddev:1353.84 (26.8%) stddev-sans-first:1320.86 16:52:02 INFO - PROCESS | 1654 | Values: 297.8 302.7 310.9 313.0 313.5 4289.5 5271.6 5297.6 5339.0 5386.5 4604.0 5032.7 5159.8 5179.4 5216.5 3585.9 3800.6 4059.7 4083.8 4104.9 3398.7 3722.7 4069.7 4076.7 4079.2 3869.9 4065.5 4065.7 4092.7 4093.2 3384.5 4053.1 4058.9 4074.8 4098.4 3527.6 3550.9 3560.7 3563.0 3566.6 4035.4 5165.4 5177.7 5180.0 5182.8 3858.9 3975.1 4088.7 4625.4 4630.2 5065.5 5464.3 5535.5 5548.3 5554.8 3875.2 3895.9 3902.7 3916.9 3985.6 5009.8 5631.8 5773.8 5798.8 5835.8 3931.5 3942.2 3959.5 3966.9 3970.7 4031.6 4037.8 4045.4 4046.9 4058.6 3698.6 4105.4 4597.3 4662.4 4734.0 4709.9 5588.2 5606.2 5616.4 5680.8 1161.8 1314.3 1342.3 1376.2 1378.9 769.4 857.5 888.7 895.2 938.0 4710.9 5192.3 5195.5 5211.1 5253.7 4722.7 5494.6 5724.9 5746.4 5806.4 4928.7 5100.3 5184.5 5232.5 5233.6 2540.8 2968.1 2999.9 3013.8 3032.4 5316.9 5337.7 5454.6 5543.0 5951.9 4657.9 4797.6 5734.0 5791.7 5797.7 5847.5 5881.2 5885.4 5893.1 5895.2 4253.9 5309.0 5678.9 5694.6 5816.6 5144.7 5374.9 5566.2 5587.7 5616.2 5008.6 5504.4 5870.8 5873.9 5889.8 3754.0 3813.8 3814.0 3871.4 3954.3 4799.4 5589.6 5604.7 5608.4 5615.4 5056.3 5107.6 5534.4 5540.9 5564.9 5284.9 5454.9 5487.3 5517.9 5550.5 4917.6 5136.2 5378.5 5381.7 5410.9 4503.2 5352.8 5410.0 5416.0 5594.8 4120.0 4497.8 4623.1 4688.7 5411.5 16:52:02 INFO - PROCESS | 1654 | 16:52:02 INFO - PROCESS | 1654 | [#5] yui.html Cycles:180 Average:25111.13 Median:3680.91 stddev:39915.02 (1084.4%) stddev-sans-first:39984.96 16:52:02 INFO - PROCESS | 1654 | Values: 716.0 780.1 790.4 798.2 801.1 17308.7 18001.9 18014.5 18042.4 18497.4 15875.4 16055.4 16853.1 16865.8 16866.9 674.8 695.4 838.0 861.0 865.3 691.0 823.2 837.1 846.1 852.3 906.4 911.5 913.9 917.2 921.1 699.5 907.6 908.3 910.9 912.5 530.9 641.9 655.5 659.8 661.5 15035.5 15470.8 15538.6 15554.8 15555.0 12462.9 13858.4 14105.7 14370.7 14702.5 11103.4 11925.6 12671.9 13031.5 13403.9 4332.5 4662.0 4687.9 4853.5 4872.7 89570.6 125659.4 126298.7 128583.0 129310.4 11054.1 11358.0 11451.3 11550.7 11565.8 22457.4 22584.8 22606.5 22796.8 22802.4 7685.2 7784.5 7862.4 7900.9 8222.0 4290.0 4343.2 4344.4 4347.6 4561.1 3571.6 3679.7 3682.1 3684.1 3686.4 642.9 647.7 662.4 674.8 678.3 552.8 610.6 623.3 636.5 640.7 8527.2 8928.0 9130.2 9278.4 9382.6 518.4 535.9 552.7 583.6 598.3 4549.5 4707.6 4722.7 4730.6 4735.6 102208.0 109690.0 153159.0 157080.0 158569.5 121706.0 126660.7 130910.0 131648.3 132368.0 113062.0 116103.4 116845.4 119680.2 121514.0 8482.7 8889.3 8916.7 9138.0 9342.9 59375.7 61423.0 61700.0 63652.7 63898.7 79442.0 91778.5 95325.0 96723.0 97296.0 10393.5 11567.4 11633.4 11732.5 11780.6 10760.4 12842.4 12955.4 12972.2 13154.3 11352.4 12000.1 12089.7 12294.9 13845.7 13044.5 13899.2 13937.0 14483.8 14484.7 13606.8 13665.5 14088.0 14225.0 14278.4 11192.9 11440.9 11813.7 11906.8 12066.8 11443.5 11561.4 11699.8 11719.5 11825.6 16:52:02 INFO - PROCESS | 1654 | -------- Summary: end -------- 16:52:02 INFO - PROCESS | 1654 | 16:52:03 INFO - TEST-INFO | 1654: exit 0 16:52:03 INFO - TEST-OK | dromaeo_css | took 1116742ms 16:52:03 INFO - TEST-START | kraken 16:52:03 INFO - operating with platform_type : mac_ 16:52:03 INFO - Initialising browser for kraken test... 16:52:03 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/tmpW5NWmw/profile http://localhost:49278/getInfo.html) 16:52:05 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 16:52:05 INFO - PROCESS | 1663 | colorDepth:24 16:52:05 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 16:52:05 INFO - PROCESS | 1663 | __metrics 16:52:05 INFO - PROCESS | 1663 | 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" 16:52:05 INFO - PROCESS | 1663 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:52:06 INFO - TEST-INFO | 1663: exit 0 16:52:06 INFO - Browser initialized. 16:52:06 INFO - Running cycle 1/1 for kraken test... 16:52:06 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/tmpW5NWmw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:52:18 INFO - PROCESS | 1664 | RSS: Main: 219459584 16:52:18 INFO - PROCESS | 1664 | 16:52:21 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection) 16:52:22 INFO - PROCESS | 1664 | RSS: Main: 269619200 16:52:22 INFO - PROCESS | 1664 | 16:52:24 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-dft) 16:52:25 INFO - PROCESS | 1664 | RSS: Main: 280264704 16:52:25 INFO - PROCESS | 1664 | 16:52:28 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-fft) 16:52:28 INFO - PROCESS | 1664 | RSS: Main: 377851904 16:52:28 INFO - PROCESS | 1664 | 16:52:31 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator) 16:52:31 INFO - PROCESS | 1664 | RSS: Main: 280539136 16:52:31 INFO - PROCESS | 1664 | 16:52:33 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:52:34 INFO - PROCESS | 1664 | RSS: Main: 281350144 16:52:34 INFO - PROCESS | 1664 | 16:52:37 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom) 16:52:37 INFO - PROCESS | 1664 | RSS: Main: 319787008 16:52:37 INFO - PROCESS | 1664 | 16:52:40 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate) 16:52:41 INFO - PROCESS | 1664 | RSS: Main: 320495616 16:52:41 INFO - PROCESS | 1664 | 16:52:43 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial) 16:52:44 INFO - PROCESS | 1664 | RSS: Main: 315924480 16:52:44 INFO - PROCESS | 1664 | 16:52:46 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:52:46 INFO - PROCESS | 1664 | RSS: Main: 290119680 16:52:46 INFO - PROCESS | 1664 | 16:52:48 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:52:49 INFO - PROCESS | 1664 | RSS: Main: 294682624 16:52:49 INFO - PROCESS | 1664 | 16:52:51 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:52:52 INFO - PROCESS | 1664 | RSS: Main: 302280704 16:52:52 INFO - PROCESS | 1664 | 16:52:54 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:52:55 INFO - PROCESS | 1664 | RSS: Main: 316448768 16:52:55 INFO - PROCESS | 1664 | 16:52:58 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:52:59 INFO - PROCESS | 1664 | RSS: Main: 310448128 16:52:59 INFO - PROCESS | 1664 | 16:53:01 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49278/tests/kraken/driver.html?testName=ai-astar) 16:53:01 INFO - PROCESS | 1664 | RSS: Main: 354394112 16:53:01 INFO - PROCESS | 1664 | 16:53:01 INFO - PROCESS | 1664 | __start_tp_report 16:53:01 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 16:53:01 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 16:53:01 INFO - PROCESS | 1664 | |i|pagename|runs| 16:53:01 INFO - PROCESS | 1664 | |0;ai-astar;116;113;113;112;112;112;112;112;112;111 16:53:01 INFO - PROCESS | 1664 | |1;audio-beat-detection;140;111;105;132;108;109;111;114;176;140 16:53:01 INFO - PROCESS | 1664 | |2;audio-dft;183;184;181;181;184;183;183;183;183;183 16:53:01 INFO - PROCESS | 1664 | |3;audio-fft;81;60;59;87;62;59;60;79;109;83 16:53:01 INFO - PROCESS | 1664 | |4;audio-oscillator;87;72;95;70;90;103;131;74;68;92 16:53:01 INFO - PROCESS | 1664 | |5;imaging-gaussian-blur;88;86;88;84;88;83;87;85;88;84 16:53:01 INFO - PROCESS | 1664 | |6;imaging-darkroom;95;95;95;95;95;96;96;95;95;94 16:53:01 INFO - PROCESS | 1664 | |7;imaging-desaturate;85;86;85;84;86;85;85;84;83;84 16:53:01 INFO - PROCESS | 1664 | |8;json-parse-financial;55;52;53;53;56;57;56;57;56;56 16:53:01 INFO - PROCESS | 1664 | |9;json-stringify-tinderbox;64;71;94;113;107;68;69;70;70;71 16:53:01 INFO - PROCESS | 1664 | |10;stanford-crypto-aes;83;84;85;83;83;82;81;82;86;86 16:53:01 INFO - PROCESS | 1664 | |11;stanford-crypto-ccm;141;140;140;127;134;150;153;157;193;137 16:53:01 INFO - PROCESS | 1664 | |12;stanford-crypto-pbkdf2;168;168;167;167;165;191;234;190;166;176 16:53:01 INFO - PROCESS | 1664 | |13;stanford-crypto-sha256-iterative;66;68;67;67;67;67;67;67;67;67 16:53:01 INFO - PROCESS | 1664 | __end_tp_report 16:53:01 INFO - PROCESS | 1664 | __start_cc_report 16:53:01 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,1953 16:53:01 INFO - PROCESS | 1664 | __end_cc_report 16:53:01 INFO - PROCESS | 1664 | __startTimestamp1477353181326__endTimestamp 16:53:01 INFO - PROCESS | 1664 | 16:53:01 INFO - PROCESS | 1664 | ------- Summary: start ------- 16:53:01 INFO - PROCESS | 1664 | Number of tests: 14 16:53:01 INFO - PROCESS | 1664 | 16:53:01 INFO - PROCESS | 1664 | [#0] ai-astar Cycles:10 Average:112.50 Median:112.00 stddev:1.35 (1.2%) stddev-sans-first:0.60 16:53:01 INFO - PROCESS | 1664 | Values: 116.0 113.0 113.0 112.0 112.0 112.0 112.0 112.0 112.0 111.0 16:53:01 INFO - PROCESS | 1664 | 16:53:01 INFO - PROCESS | 1664 | [#1] audio-beat-detection Cycles:10 Average:124.60 Median:123.00 stddev:22.50 (18.3%) stddev-sans-first:23.16 16:53:01 INFO - PROCESS | 1664 | Values: 140.0 111.0 105.0 132.0 108.0 109.0 111.0 114.0 176.0 140.0 16:53:01 INFO - PROCESS | 1664 | 16:53:01 INFO - PROCESS | 1664 | [#2] audio-dft Cycles:10 Average:182.80 Median:183.00 stddev:1.03 (0.6%) stddev-sans-first:1.09 16:53:01 INFO - PROCESS | 1664 | Values: 183.0 184.0 181.0 181.0 184.0 183.0 183.0 183.0 183.0 183.0 16:53:01 INFO - PROCESS | 1664 | 16:53:01 INFO - PROCESS | 1664 | [#3] audio-fft Cycles:10 Average:73.90 Median:70.50 stddev:16.78 (23.8%) stddev-sans-first:17.60 16:53:01 INFO - PROCESS | 1664 | Values: 81.0 60.0 59.0 87.0 62.0 59.0 60.0 79.0 109.0 83.0 16:53:01 INFO - PROCESS | 1664 | 16:53:01 INFO - PROCESS | 1664 | [#4] audio-oscillator Cycles:10 Average:88.20 Median:80.50 stddev:19.21 (23.9%) stddev-sans-first:20.37 16:53:01 INFO - PROCESS | 1664 | Values: 87.0 72.0 95.0 70.0 90.0 103.0 131.0 74.0 68.0 92.0 16:53:01 INFO - PROCESS | 1664 | 16:53:01 INFO - PROCESS | 1664 | [#5] imaging-gaussian-blur Cycles:10 Average:86.10 Median:87.50 stddev:1.97 (2.3%) stddev-sans-first:1.96 16:53:01 INFO - PROCESS | 1664 | Values: 88.0 86.0 88.0 84.0 88.0 83.0 87.0 85.0 88.0 84.0 16:53:01 INFO - PROCESS | 1664 | 16:53:01 INFO - PROCESS | 1664 | [#6] imaging-darkroom Cycles:10 Average:95.10 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 16:53:01 INFO - PROCESS | 1664 | Values: 95.0 95.0 95.0 95.0 95.0 96.0 96.0 95.0 95.0 94.0 16:53:01 INFO - PROCESS | 1664 | 16:53:01 INFO - PROCESS | 1664 | [#7] imaging-desaturate Cycles:10 Average:84.70 Median:85.00 stddev:0.95 (1.1%) stddev-sans-first:1.00 16:53:01 INFO - PROCESS | 1664 | Values: 85.0 86.0 85.0 84.0 86.0 85.0 85.0 84.0 83.0 84.0 16:53:01 INFO - PROCESS | 1664 | 16:53:01 INFO - PROCESS | 1664 | [#8] json-parse-financial Cycles:10 Average:55.10 Median:56.00 stddev:1.79 (3.2%) stddev-sans-first:1.90 16:53:01 INFO - PROCESS | 1664 | Values: 55.0 52.0 53.0 53.0 56.0 57.0 56.0 57.0 56.0 56.0 16:53:01 INFO - PROCESS | 1664 | 16:53:01 INFO - PROCESS | 1664 | [#9] json-stringify-tinderbox Cycles:10 Average:79.70 Median:70.00 stddev:17.94 (25.6%) stddev-sans-first:18.10 16:53:01 INFO - PROCESS | 1664 | Values: 64.0 71.0 94.0 113.0 107.0 68.0 69.0 70.0 70.0 71.0 16:53:01 INFO - PROCESS | 1664 | 16:53:01 INFO - PROCESS | 1664 | [#10] stanford-crypto-aes Cycles:10 Average:83.50 Median:83.50 stddev:1.72 (2.1%) stddev-sans-first:1.81 16:53:01 INFO - PROCESS | 1664 | Values: 83.0 84.0 85.0 83.0 83.0 82.0 81.0 82.0 86.0 86.0 16:53:01 INFO - PROCESS | 1664 | 16:53:01 INFO - PROCESS | 1664 | [#11] stanford-crypto-ccm Cycles:10 Average:147.20 Median:145.50 stddev:18.45 (12.7%) stddev-sans-first:19.43 16:53:01 INFO - PROCESS | 1664 | Values: 141.0 140.0 140.0 127.0 134.0 150.0 153.0 157.0 193.0 137.0 16:53:01 INFO - PROCESS | 1664 | 16:53:01 INFO - PROCESS | 1664 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.20 Median:172.00 stddev:21.59 (12.6%) stddev-sans-first:22.51 16:53:01 INFO - PROCESS | 1664 | Values: 168.0 168.0 167.0 167.0 165.0 191.0 234.0 190.0 166.0 176.0 16:53:01 INFO - PROCESS | 1664 | 16:53:01 INFO - PROCESS | 1664 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.00 Median:67.00 stddev:0.47 (0.7%) stddev-sans-first:0.33 16:53:01 INFO - PROCESS | 1664 | Values: 66.0 68.0 67.0 67.0 67.0 67.0 67.0 67.0 67.0 67.0 16:53:01 INFO - PROCESS | 1664 | -------- Summary: end -------- 16:53:01 INFO - PROCESS | 1664 | 16:53:02 INFO - TEST-INFO | 1664: exit 0 16:53:02 INFO - TEST-OK | kraken | took 59152ms 16:53:02 INFO - SUITE-END | took 1175s 16:53:02 INFO - Completed test suite (00:19:36) 16:53:02 INFO - javascript benchmark 16:53:02 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3285.605727312723, 3328.534005239266, 3363.0, 3384.0862966998925, 3393.864245430183, 5447.9182812257795, 5682.062459694151, 5700.401457846918, 5716.39977802331, 5723.313202415706, 4550.704204226723, 5681.57388195886, 5759.4816466518005, 5760.135979603062, 5768.884622307552, 5192.818251361203, 5200.297959775429, 5225.764840582183, 5238.842834714946, 5251.658642188265, 5165.276861239429, 5170.9224361634415, 5176.896462070775, 5318.228856815759, 5345.984262990039, 5211.114110601188, 5247.895042099175, 5270.446603106676, 5292.153255479124, 5318.813841515548, 5199.66202196856, 5209.1665333546825, 5228.660137091077, 5260.605454590921, 5278.551323137538, 5097.108006098955, 5181.0, 5190.818321358772, 5223.73881305937, 5267.683938963636, 5523.696196709179, 5599.888002239933, 5599.8880022399735, 5607.411221821691, 5700.429957004308, 6201.069839524062, 6257.9687101564205, 6272.247330320391, 6278.748850045993, 6290.276618188921, 6266.028765541303, 6325.557210995232, 6326.715297811609, 6331.2719037310835, 6355.777547785805, 5442.482964118402, 5448.618596698193, 5478.45215478449, 5791.986402379567, 5795.362510123883, 6221.688915554204, 6273.498120150377, 6274.152989346379, 6276.152719382825, 6297.811065668037, 6158.938410615837, 6160.476359509402, 6162.352952939877, 6168.228971378578, 6171.043488259327, 6139.692169977852, 6249.406306400884, 6296.5592408531875, 6314.652694101868, 6328.11406403104, 6079.08813677943, 6107.378879269592, 6112.633242005494, 6139.815805525842, 6201.0, 6213.751449942042, 6246.031865060922, 6253.593516421509, 6284.308726040132, 6284.465820405317, 4921.9015619688125, 4949.82675606352, 4950.2822090796635, 4966.15575352191, 4989.226669866177, 4329.480462344539, 4347.673924455616, 4410.977945110318, 4411.779411029435, 4453.42105526286, 5811.941880581225, 5821.09772985188, 5887.793927212527, 5915.319738230072, 5927.644341339534, 6225.221847269091, 6259.843503912439, 6268.4671802896355, 6272.968635156795, 6281.748730050838, 5646.85882852932, 5649.519790817826, 5737.3975732548315, 5875.529957603381, 5881.823545293648, 4595.793189306422, 5331.280277162611, 5379.246905433242, 5391.2182733504205, 5412.83761487156, 6280.340564240872, 6331.841703957346, 6361.045843123568, 6370.904436433457, 6377.74489020434, 6258.155149055002, 6282.528810339151, 6286.434220919956, 6294.527910406646, 6301.6219026858535, 6220.315765265907, 6265.498760099273, 6274.152989346562, 6286.899792536196, 6314.936850631619, 6180.165677633642, 6195.783147589813, 6203.193584833943, 6206.06908963641, 6230.937690623217, 6181.320054794059, 6183.659898705434, 6241.843953901189, 6250.0, 6265.96867015662, 5940.287165540163, 6011.308699499438, 6140.232470941132, 6158.784442544489, 6173.197484327188, 5446.264754258283, 5609.074502707008, 5770.798022069207, 5799.101139323411, 5849.005669036189, 5656.6888821115235, 5664.971675141598, 5672.914906276492, 5675.602707810579, 5693.800716974886, 5743.10981797822, 5781.682007489629, 5787.189793428839, 5806.216160818235, 5949.137375080676, 5833.095870140135, 5875.73559189846, 5903.822885313448, 5949.25634295713, 5967.6419414835245], "value": 5669.724462679909, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [455.32157085941947, 484.64862974343595, 493.2010143567421, 498.18297991294304, 542.3464725006368, 30788.460576971145, 41007.58992410079, 42060.94847628807, 42146.0, 44582.0, 43511.34732979008, 43845.9038524037, 43858.780706096506, 43963.56036439635, 44014.11971760563, 8762.912370876305, 9862.16171625411, 10051.798964020716, 10052.246081543877, 10055.24585656075, 8982.0, 12069.758604827899, 12343.13598048137, 12349.814752778715, 12538.623841284776, 10221.386716797017, 12402.573704024016, 12429.937850310782, 12436.191647542926, 12462.065345099154, 12358.0, 12421.44103515335, 12442.0, 12442.004639628807, 12449.875501244962, 12104.394780260951, 12132.818007729891, 12141.817872731915, 12152.57465988686, 12167.087468439902, 39850.40224396636, 40204.39693404601, 40243.7987810062, 40364.99087522806, 40424.191516169514, 3334.2664613784927, 3468.167639766438, 3589.1565482111682, 3905.3360928641914, 3987.641112299878, 29145.56281655777, 29879.80480780766, 30474.085777426713, 31806.840965795254, 31913.84043079793, 3091.3971775503787, 3117.407692538433, 3274.5088236764436, 3300.207950091961, 3305.2232725309773, 514562.0, 515595.0, 515922.0, 516174.0, 517014.41492792673, 49227.753861231184, 60416.69791651014, 61009.0, 61044.38955610476, 61075.38924610786, 58605.70697146572, 58629.827403451694, 58698.82602347929, 58718.0, 58840.0, 16727.247273872705, 16948.74576881348, 16961.982281063174, 16973.40593079261, 16994.660106797794, 11773.352465614302, 11986.760264794655, 11993.760124797454, 13164.0, 13247.867521324664, 13016.349182540835, 14063.60918643833, 14592.562223133324, 16244.675106497803, 16581.419650312422, 5405.972970135124, 6353.174087368612, 6411.326810684905, 6419.1334169887405, 6514.771982980573, 880.8150288439372, 959.8704174936433, 973.9870534643891, 1048.6853943816825, 1105.1048650593045, 28853.422931541252, 28892.0, 29350.853245733633, 29526.852365738036, 29646.258843529085, 1042.781015986636, 1047.3925123428394, 1112.0, 1138.3511398502774, 1141.7259857634108, 14834.96877787698, 15168.620784480476, 15492.922535387477, 15593.376264949502, 15769.132697701507, 665578.0, 670727.0, 671851.0, 672029.0, 673849.0, 58221.0, 58250.70874645685, 58395.12407313893, 58497.0, 58547.12179317314, 1210.1831263896834, 1256.472281641731, 1356.2947267421084, 1358.3615700620692, 1391.24177323359, 29674.851625741732, 31230.843845780626, 31906.52140217852, 31938.68061319357, 33096.50355244721, 49317.50682493272, 49712.0, 50040.99918001547, 50050.99898001947, 50056.748581285756, 559425.2028739831, 560520.0, 563260.1836990789, 563404.0, 663958.0, 24994.125205617715, 25990.0, 26029.39706029382, 26053.218403447925, 26058.869705651352, 37313.835635607924, 45795.48124853165, 45863.0, 46687.06625867532, 46815.29777053279, 38154.0, 38408.615913840506, 38948.0, 40069.59930400775, 40109.79945100256, 38280.617193827704, 38313.616863831005, 38808.029799255244, 39742.40386394149, 40037.0, 37041.62958370382, 38490.615093849825, 39218.41172382359, 39404.0, 40791.7960410196, 31280.0, 35196.64803352036, 35416.811998604404, 35537.0, 35771.64228357683, 24446.877765611058, 25872.223833285032, 25956.480870382107, 26216.082437115372, 26525.0], "value": 20345.970215299203, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.02765340008165, 372.86017743346247, 373.99065023374413, 383.63746259784506, 405.1168452772954, 15502.612434689125, 15530.922345388271, 20409.591808163845, 21349.466263343424, 22252.221172258975, 20086.196552137935, 20791.584168316625, 20989.055492502837, 21018.369448916517, 21095.472613184676, 5319.441458646835, 5605.859853503654, 5611.0741727614895, 5634.4365563443735, 5638.661680299195, 5491.515608385034, 5563.749631266603, 6021.157038014681, 6158.722857471402, 6183.845403864894, 6158.445739883409, 6162.260528736558, 6163.0, 6165.229346331708, 6169.19800425944, 4709.481956984729, 5591.860203494904, 5604.103343465067, 5612.382637909826, 5720.170575266584, 5252.894942101137, 5447.863803404907, 5456.590755693339, 5463.808766693147, 5464.28964234647, 25625.23124306274, 26026.609600856, 26127.477450450886, 26348.73651263482, 26529.93880244788, 2091.7699053104147, 2240.820734341265, 2564.743525647439, 3031.348260124084, 3057.4038062577806, 9993.750156246151, 10403.063724264777, 10894.074003709695, 15828.287727052195, 16327.428540001043, 1570.5994971282364, 1622.8863979521439, 1828.5520047588293, 1842.1618163735468, 1949.5475870476444, 124708.0, 129635.71895489619, 135404.0, 136477.31761341228, 136823.0, 9827.82066152052, 10428.426436546062, 11247.775044499063, 11294.0, 11586.536538538365, 13682.110662806886, 13842.723145537033, 26866.32834179122, 35453.113672158404, 35797.0, 9962.800743985224, 10799.272116461329, 11032.227744058, 11360.431978401048, 11710.0, 8840.425372350777, 9087.591058402388, 9185.402948808307, 9217.76955576116, 9530.666426675032, 9594.808103837884, 9709.0, 9815.852762208573, 9947.701568952943, 9968.551415186334, 3698.204885949534, 3757.8204641307975, 3768.8304026318333, 3770.67949224319, 3809.7523660961947, 352.2707994451461, 389.974651647642, 402.22170100854356, 410.4848415238916, 815.3640160674684, 11462.713432164262, 11769.470373833197, 11791.705207369885, 11874.406279685982, 12184.329861857506, 433.3175248982865, 434.3961892968776, 441.60255769806787, 451.21263395374626, 465.71358614452544, 5419.945800542023, 5990.580659353804, 6045.697715114226, 6138.324784273726, 6199.473044791243, 611587.0, 625802.0, 627237.0, 627708.0, 632393.0, 146159.0, 150345.0, 151221.2438937798, 151792.0, 156223.0, 434.20757118259303, 439.2159994410027, 439.3585365366537, 439.3695047606695, 439.42654835440055, 11066.557337706723, 11112.611058612909, 11134.888651113386, 11446.255993360404, 11465.713357166138, 49273.50726492689, 51701.741491292305, 52752.4174274757, 52784.47215527796, 54751.72624137013, 64010.0, 80463.59768201121, 80687.78968315598, 84695.0, 87313.0, 8076.27313541784, 8138.389620778584, 8196.344292456708, 8316.0, 8592.441491303045, 10315.896841031794, 12140.210886292363, 12309.830566096207, 12763.106582539132, 13015.739685206054, 5437.619366644297, 5544.085225937836, 10833.724917767042, 11185.937335953071, 11256.099512038854, 9548.901876284317, 10732.517036733521, 11250.324980501196, 11407.315561066363, 11782.469788859693, 11265.26775759573, 11310.208285419942, 11491.080713543064, 11575.363355015555, 11775.116866234895, 7008.439324854102, 7452.813679658052, 7506.699732010658, 8125.79685507867, 8457.915420845713, 11203.047740942111, 11369.886301136883, 11381.032612228053, 11507.424628768695, 11787.941060294643], "value": 9209.911917003483, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1342.0069148829868, 1506.179132372857, 1523.6571771351444, 1702.9403970861022, 1867.6544839204757, 7915.287624113828, 8414.200650938159, 8750.562471876412, 8950.910490895089, 9009.369344145913, 8889.955550222256, 8973.91026089739, 8977.19205271527, 8978.91021089789, 8991.775205619862, 1981.1580078466666, 2358.69336986192, 2389.2712722619594, 2391.8086553075714, 2392.055138220402, 2356.752540983189, 2380.321608341623, 2409.734929157791, 2420.576399130145, 2427.8057755379527, 2364.6216605343147, 2371.988140059306, 2380.773826486481, 2395.2933884504114, 2408.397900524869, 2355.057976809273, 2370.6562548430466, 2382.213869423086, 2387.6299173628117, 2388.5222955408813, 2332.1720789119786, 2363.3855197648736, 2368.988155059231, 2372.454335502844, 2372.833901626887, 8663.220310172052, 8894.59974301158, 8945.194932456045, 9037.412568183046, 9069.863952040725, 5328.6802791832615, 5490.423505531941, 5491.0, 5496.780128794843, 5499.587530935156, 3632.7820330780237, 3661.743677942545, 3699.223163135745, 3732.1789206374824, 3735.1595890924596, 1092.2354351953663, 1107.141964977141, 1114.0, 1114.9944250278777, 1115.709915421988, 11874.703132421671, 11954.521819127225, 11998.760024799456, 12008.939955300255, 12020.939895300555, 3655.7258205634153, 3916.9608303917166, 4523.819047238139, 4691.929621055687, 4711.316859055486, 9245.260379169651, 9245.445273283625, 9248.583813728399, 9251.398659087137, 9261.768455788659, 5305.124654431976, 5305.787768489217, 5309.964556911365, 5310.654807437504, 5322.33470816148, 4446.1330040642415, 4471.28459446487, 4644.930326045112, 4646.86059418218, 4995.400551933719, 3794.829232684536, 3831.8084095795098, 3862.343401621731, 3907.6092390760696, 3918.294706952719, 992.8560358747941, 1188.5245901639241, 1188.5245901639414, 1202.60314096348, 1204.0668481926577, 908.6683360573343, 913.7923369868568, 919.7102912582515, 940.0, 944.6363150187227, 5741.45456181662, 5864.091065884794, 5924.259467566554, 6117.327094019654, 6200.503959683215, 356.98750543730847, 380.739193652349, 417.912238429927, 440.78842155765415, 462.93981782368076, 3904.160605469781, 3942.4677668514955, 3954.426608141861, 4046.858359957387, 4076.267586275821, 10636.893631063745, 10727.141828653845, 11126.38804865724, 11143.442827858575, 11192.608258711069, 10868.0, 11071.5571377144, 11076.556937722562, 11082.556697732, 11096.55613775456, 8265.669373225124, 8373.790655233668, 8411.158884111539, 8498.277646400125, 8607.956960215284, 5751.539876809761, 5966.492848107786, 5967.9701601491715, 6036.426539478743, 6107.053406721965, 10006.949965250127, 10164.644237451654, 10246.948765256126, 10255.538500767334, 10280.331778434676, 9540.23678105736, 9935.900640993497, 10348.637797677045, 10456.111230545488, 10655.200859935381, 6815.522913396015, 6927.68825402848, 7691.115521714851, 7695.730649427243, 7809.0, 8267.586620668822, 8294.502329860228, 8375.41372103947, 8381.329493640616, 8463.28062114727, 7490.715331823846, 7500.437467189873, 7515.6993720250575, 7533.096028476618, 7566.546007239584, 7343.559386436831, 7350.264973502607, 7390.9260907390235, 7437.962810185914, 7518.887216691864, 5265.710385928811, 6994.650267486503, 7012.789616311519, 7080.398166155935, 7088.0076789248515, 4813.879653008703, 4869.245266983623, 4882.633802464758, 5004.974975125101, 5103.489651034867, 4932.950670493249, 4944.48082951285, 4961.181405068268, 4997.675151115166, 5053.67151135175], "value": 4492.169107071218, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.784106522771, 302.6973026973027, 310.9129443755748, 312.9624445066592, 313.4608473425707, 4289.528151903288, 5271.604629652777, 5297.602679799012, 5338.973305133469, 5386.542143917772, 4603.976980115095, 5032.698038117706, 5159.807081990149, 5179.378474583046, 5216.452272511379, 3585.910352241195, 3800.638939300762, 4059.6955228357842, 4083.836646534135, 4104.8563300284495, 3398.660133986594, 3722.7394082414244, 4069.674426045909, 4076.694247931402, 4079.2045551117544, 3869.8839034829, 4065.512138543364, 4065.6747460203114, 4092.6725861930972, 4093.219449539445, 3384.526166336715, 4053.108316170438, 4058.9446743846515, 4074.8166332515107, 4098.364753463203, 3527.5766907971206, 3550.8579656813695, 3560.7211384713546, 3563.0, 3566.554180727409, 4035.3543433050572, 5165.405978312505, 5177.663451875616, 5180.0, 5182.792688292501, 3858.884233473, 3975.105601239698, 4088.7342322748927, 4625.421822272216, 4630.236011058171, 5065.518775716301, 5464.26232458617, 5535.529479994206, 5548.306461692288, 5554.805581804618, 3875.205582855508, 3895.8831235062994, 3902.7268091233627, 3916.941245881314, 3985.5815139410233, 5009.774560144766, 5631.774729010835, 5773.826785196451, 5798.82603521895, 5835.824925252249, 3931.5282166140246, 3942.211557688474, 3959.5248570171766, 3966.940495892564, 3970.68234541236, 4031.576684448091, 4037.818298176589, 4045.352743561075, 4046.8785936421364, 4058.559211479882, 3698.593154752975, 4105.445764821711, 4597.310403439511, 4662.393888794435, 4733.952660473351, 4709.882252943636, 5588.189712491666, 5606.206014075457, 5616.410276920865, 5680.801171958961, 1161.7908776420325, 1314.2574445438297, 1342.2952949701485, 1376.167418711685, 1378.9448422063006, 769.3844924060729, 857.498363457372, 888.6667499687617, 895.2211575928853, 937.9765505862408, 4710.940038491381, 5192.324997750268, 5195.454477279907, 5211.062008838369, 5253.711045892513, 4722.669413141047, 5494.560435165177, 5724.942750572524, 5746.396628353963, 5806.419358064244, 4928.722399604223, 5100.260462232955, 5184.481551844785, 5232.450592687713, 5233.607479439057, 2540.7713305802567, 2968.1449094316054, 2999.9400011999637, 3013.789034767589, 3032.3935212957495, 5316.946830531722, 5337.679739215582, 5454.563634909197, 5543.0, 5951.869144862462, 4657.930131048037, 4797.56821886032, 5733.971330143323, 5791.681457519792, 5797.710114494258, 5847.473727364558, 5881.235439392852, 5885.352611212677, 5893.086571581326, 5895.233619629421, 4253.893652658709, 5309.0, 5678.914816277676, 5694.629849059798, 5816.621919575214, 5144.717040562805, 5374.925014997095, 5566.22072909801, 5587.69267690281, 5616.157576363578, 5008.649394542347, 5504.394516603251, 5870.7945221917025, 5873.913326034697, 5889.793857214977, 3753.9624603754705, 3813.7521061130938, 3813.985944275554, 3871.438641397038, 3954.3079961006133, 4799.400074990626, 5589.608727389043, 5604.747786349705, 5608.383077861514, 5615.438456154352, 5056.342675452168, 5107.642465027412, 5534.446555344433, 5540.94984855606, 5564.944350556442, 5284.894302114013, 5454.945450545444, 5487.259220005249, 5517.889642207214, 5550.5282051026115, 4917.582005529427, 5136.203888397305, 5378.542823860016, 5381.704006279692, 5410.8637186191345, 4503.2212276151395, 5352.812651557177, 5410.026195284887, 5416.0, 5594.776208951596, 4119.97940010298, 4497.797599108113, 4623.052274283831, 4688.6717929745955, 5411.512963833116], "value": 4044.027946545069, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [715.9987143669493, 780.0795061827049, 790.383500869322, 798.2456578533285, 801.0907619851466, 17308.74036889446, 18001.90999045006, 18014.549636259115, 18042.368517101902, 18497.445076647688, 15875.444359447421, 16055.438059667884, 16853.073080980572, 16865.83134168658, 16866.90365126266, 674.7840690978888, 695.4436450839333, 837.9790505237372, 861.0313397427893, 865.3336930563462, 690.9792706218797, 823.2097186700775, 837.1419295222385, 846.124261389462, 852.3479538153317, 906.4380084347681, 911.5260064766318, 913.899471058183, 917.1837065012111, 921.138735282513, 699.4614147106699, 907.5598334807613, 908.2733812949614, 910.9134632209963, 912.4662072687486, 530.864629519474, 641.8908785506475, 655.4592461219513, 659.7855696898479, 661.503872095928, 15035.54893353201, 15470.767938480933, 15538.61153471161, 15554.766678499831, 15555.0, 12462.940650044757, 13858.4456621735, 14105.717885642332, 14370.712585748226, 14702.4854130106, 11103.44482775858, 11925.582604608884, 12671.87600648113, 13031.543895963596, 13403.86596134036, 4332.458442694663, 4662.0, 4687.929681054787, 4853.538913803183, 4872.731999740013, 89570.55214723885, 125659.37170314182, 126298.73701262962, 128583.0, 129310.41379172458, 11054.115670746482, 11357.97778199966, 11451.25566838153, 11550.653480395602, 11565.76868462626, 22457.43856403603, 22584.774152258597, 22606.547869042857, 22796.772032279467, 22802.42993925165, 7685.193054729173, 7784.494007889469, 7862.449628525948, 7900.881486777703, 8221.958890205511, 4289.978550107229, 4343.2399330117105, 4344.370066340363, 4347.56524347569, 4561.087782443525, 3571.5535558055244, 3679.7240206984584, 3682.0978860178893, 3684.1342284563107, 3686.3733165361955, 642.8971364581645, 647.685872351909, 662.3872917551342, 674.780696273713, 678.2708588267632, 552.809280798124, 610.5817515002237, 623.2894500269696, 636.4939872801134, 640.7308930249307, 8527.232549070613, 8928.0, 9130.223930965942, 9278.443293402417, 9382.624695012122, 518.3831240823483, 535.8713908661971, 552.7181137619763, 583.585654185533, 598.2611474828583, 4549.545045495424, 4707.576318131385, 4722.669413141047, 4730.645201609893, 4735.644826638016, 102208.0, 109690.0, 153159.0, 157080.0, 158569.5215906638, 121706.0, 126660.73339266673, 130910.0, 131648.3417582906, 132368.0, 113062.0, 116103.41948290373, 116845.41577292059, 119680.20479692986, 121514.0, 8482.708897894387, 8889.288856891304, 8916.687915922912, 9137.954310228672, 9342.906570934203, 59375.70312148412, 61423.0, 61700.0, 63652.68173659102, 63898.68050659717, 79442.0, 91778.54110729403, 95325.0, 96723.0, 97296.0, 10393.480325983519, 11567.421628918351, 11633.418329083343, 11732.472038758448, 11780.646580602595, 10760.3543787373, 12842.391088912836, 12955.417006234553, 12972.221666700028, 13154.34228288601, 11352.432378380883, 12000.099992500423, 12089.69775755578, 12294.87705122973, 13845.653858653212, 13044.478220871439, 13899.23554204488, 13937.0, 14483.782743259071, 14484.710305793615, 13606.795898061338, 13665.521706740217, 14088.0, 14225.00424970242, 14278.357473913491, 11192.888071119185, 11440.94279528597, 11813.704657383634, 11906.76186476283, 12066.818997714865, 11443.48504317291, 11561.393841006133, 11699.766004680028, 11719.472623731783, 11825.645230643095], "value": 7859.154215337981, "unit": "score"}], "name": "dromaeo_css", "value": 7302.948690070806, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 113.0, 113.0, 112.0, 112.0, 112.0, 112.0, 112.0, 112.0, 111.0], "value": 112.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 111.0, 105.0, 132.0, 108.0, 109.0, 111.0, 114.0, 176.0, 140.0], "value": 124.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 184.0, 181.0, 181.0, 184.0, 183.0, 183.0, 183.0, 183.0, 183.0], "value": 182.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 60.0, 59.0, 87.0, 62.0, 59.0, 60.0, 79.0, 109.0, 83.0], "value": 73.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 72.0, 95.0, 70.0, 90.0, 103.0, 131.0, 74.0, 68.0, 92.0], "value": 88.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 88.0, 84.0, 88.0, 83.0, 87.0, 85.0, 88.0, 84.0], "value": 86.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 96.0, 95.0, 95.0, 94.0], "value": 95.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 85.0, 84.0, 86.0, 85.0, 85.0, 84.0, 83.0, 84.0], "value": 84.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 52.0, 53.0, 53.0, 56.0, 57.0, 56.0, 57.0, 56.0, 56.0], "value": 55.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 71.0, 94.0, 113.0, 107.0, 68.0, 69.0, 70.0, 70.0, 71.0], "value": 79.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 85.0, 83.0, 83.0, 82.0, 81.0, 82.0, 86.0, 86.0], "value": 83.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 140.0, 140.0, 127.0, 134.0, 150.0, 153.0, 157.0, 193.0, 137.0], "value": 147.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 168.0, 167.0, 167.0, 165.0, 191.0, 234.0, 190.0, 166.0, 176.0], "value": 179.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 67.0, 67.0, 67.0, 67.0, 67.0, 67.0, 67.0, 67.0], "value": 67.0, "unit": "score"}], "name": "kraken", "value": 1459.6000000000004, "alertThreshold": 2.0}]} 16:53:02 INFO - Return code: 0 16:53:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:53:02 INFO - # TBPL SUCCESS # 16:53:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:53:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:53:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:53:02 INFO - 2016-10-24 16:53:02.654 screenresolution[1668:10313] starting screenresolution argv=screenresolution get 16:53:02 INFO - 2016-10-24 16:53:02.657 screenresolution[1668:10313] Display 0: 1600x1200x32@60 16:53:02 INFO - 2016-10-24 16:53:02.663 screenresolution[1669:10316] starting screenresolution argv=screenresolution list 16:53:02 INFO - Available Modes on Display 0 16:53:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:53:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:53:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:53:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:53:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:53:02 INFO - Intel Iris: 16:53:02 INFO - Chipset Model: Intel Iris 16:53:02 INFO - Type: GPU 16:53:02 INFO - Bus: Built-In 16:53:02 INFO - VRAM (Dynamic, Max): 1536 MB 16:53:02 INFO - Vendor: Intel (0x8086) 16:53:02 INFO - Device ID: 0x0a2e 16:53:02 INFO - Revision ID: 0x0009 16:53:02 INFO - Displays: 16:53:02 INFO - Display: 16:53:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:53:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:53:02 INFO - Display Serial Number: 1600x1200 60 16:53:02 INFO - Main Display: Yes 16:53:02 INFO - Mirror: Off 16:53:02 INFO - Online: Yes 16:53:02 INFO - Rotation: Supported 16:53:02 INFO - Return code: 0 16:53:02 INFO - Running post-action listener: _resource_record_post_action 16:53:02 INFO - [mozharness: 2016-10-24 23:53:02.902967Z] Finished run-tests step (success) 16:53:02 INFO - Running post-run listener: _resource_record_post_run 16:53:02 INFO - Total resource usage - Wall time: 1209s; CPU: 53.0%; Read bytes: 36733952; Write bytes: 661212160; Read time: 242; Write time: 1881 16:53:02 INFO - TinderboxPrint: CPU usage
53.3% 16:53:02 INFO - TinderboxPrint: I/O read bytes / time
36,733,952 / 242 16:53:02 INFO - TinderboxPrint: I/O write bytes / time
661,212,160 / 1,881 16:53:02 INFO - TinderboxPrint: CPU idle
2,258.3 (46.7%) 16:53:02 INFO - TinderboxPrint: CPU system
968.8 (20.0%) 16:53:02 INFO - TinderboxPrint: CPU user
1,610.5 (33.3%) 16:53:02 INFO - TinderboxPrint: Swap in / out
400,289,792 / 0 16:53:02 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 358717952; Write bytes: 355698176; Read time: 27707; Write time: 740 16:53:02 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 34751488; Write bytes: 294434304; Read time: 220; Write time: 1108 16:53:03 INFO - Running post-run listener: _upload_blobber_files 16:53:03 INFO - Blob upload gear active. 16:53:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:53:03 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 16:53:03 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:53:03 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:53:03 INFO - (blobuploader) - INFO - Open directory for files ... 16:53:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:53:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:53:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:53:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:53:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:53:05 INFO - (blobuploader) - INFO - Done attempting. 16:53:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:53:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:53:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:53:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:53:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:53:06 INFO - (blobuploader) - INFO - Done attempting. 16:53:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:53:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:53:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:53:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:53:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:53:08 INFO - (blobuploader) - INFO - Done attempting. 16:53:08 INFO - (blobuploader) - INFO - Iteration through files over. 16:53:08 INFO - Return code: 0 16:53:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:53:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:53:08 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/078fd65cfbfbb655bbf875e5b49432923ddadbaf938d6ddb1aad693854163809769dee19b5e9d41d4b8badf07535351b7b4bae6dc8783bad7f834f2757c00327", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4f67fb84f6cb4c915ae0454a34b9696636e1ee3b199b9390b6bc5676432251c2999a8f53e1e0b27f43f23d63009be4785639f969414320a46f599f25b5e03798", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e571bfc8fa16eb53cf80a695c4e8638ded3253c1e7ba7bdfb9ced02614f82e0ba2c0097cf64310226402ee8a545308613f0bc1161981fa8b34db79219b6eba9d"} 16:53:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:53:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:53:08 INFO - Contents: 16:53:08 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/078fd65cfbfbb655bbf875e5b49432923ddadbaf938d6ddb1aad693854163809769dee19b5e9d41d4b8badf07535351b7b4bae6dc8783bad7f834f2757c00327", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4f67fb84f6cb4c915ae0454a34b9696636e1ee3b199b9390b6bc5676432251c2999a8f53e1e0b27f43f23d63009be4785639f969414320a46f599f25b5e03798", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e571bfc8fa16eb53cf80a695c4e8638ded3253c1e7ba7bdfb9ced02614f82e0ba2c0097cf64310226402ee8a545308613f0bc1161981fa8b34db79219b6eba9d"} 16:53:08 INFO - Running post-run listener: copy_logs_to_upload_dir 16:53:08 INFO - Copying logs to upload dir... 16:53:08 INFO - mkdir: /builds/slave/test/build/upload/logs 16:53:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.158265 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-10-24 16:53:08.743698) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-24 16:53:08.748359) ========= 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.Sz7xkMX6RZ/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.Ryat8Qt8Ko/Listeners TMPDIR=/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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/Mozilla-Aurora/sha512/078fd65cfbfbb655bbf875e5b49432923ddadbaf938d6ddb1aad693854163809769dee19b5e9d41d4b8badf07535351b7b4bae6dc8783bad7f834f2757c00327", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4f67fb84f6cb4c915ae0454a34b9696636e1ee3b199b9390b6bc5676432251c2999a8f53e1e0b27f43f23d63009be4785639f969414320a46f599f25b5e03798", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e571bfc8fa16eb53cf80a695c4e8638ded3253c1e7ba7bdfb9ced02614f82e0ba2c0097cf64310226402ee8a545308613f0bc1161981fa8b34db79219b6eba9d"} build_url:https://queue.taskcluster.net/v1/task/WC1P55XORRylbGVOut0Z5A/artifacts/public/build/firefox-51.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011024 build_url: 'https://queue.taskcluster.net/v1/task/WC1P55XORRylbGVOut0Z5A/artifacts/public/build/firefox-51.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/078fd65cfbfbb655bbf875e5b49432923ddadbaf938d6ddb1aad693854163809769dee19b5e9d41d4b8badf07535351b7b4bae6dc8783bad7f834f2757c00327", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4f67fb84f6cb4c915ae0454a34b9696636e1ee3b199b9390b6bc5676432251c2999a8f53e1e0b27f43f23d63009be4785639f969414320a46f599f25b5e03798", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e571bfc8fa16eb53cf80a695c4e8638ded3253c1e7ba7bdfb9ced02614f82e0ba2c0097cf64310226402ee8a545308613f0bc1161981fa8b34db79219b6eba9d"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-24 16:53:08.786693) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 16:53:08.787134) ========= 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.Sz7xkMX6RZ/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.Ryat8Qt8Ko/Listeners TMPDIR=/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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.005080 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 16:53:08.839426) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-24 16:53:08.839886) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-24 16:53:08.840338) ========= ========= Total master_lag: 0.21 =========