builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0014 starttime: 1491177701.62 results: success (0) buildid: 20170402155023 builduid: a344c0cea70f4d90b1521c3973d2e15f revision: f0bf3f31114aceba2ab4d71da5686ebc3ae94ea9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-02 17:01:41.621020) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-02 17:01:41.621456) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-02 17:01:41.621728) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-02 17:01:41.648166) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-02 17:01:41.648449) ========= 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.E6YrsJP9nX/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.COEmlzNdFr/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 --2017-04-02 17:01:41-- 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% 147M=0s 2017-04-02 17:01:41 (147 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.172954 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-02 17:01:41.842223) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-02 17:01:41.842534) ========= 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.E6YrsJP9nX/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.COEmlzNdFr/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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.035544 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-02 17:01:41.897617) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-02 17:01:41.898009) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev f0bf3f31114aceba2ab4d71da5686ebc3ae94ea9 --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-beta --rev f0bf3f31114aceba2ab4d71da5686ebc3ae94ea9 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.E6YrsJP9nX/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.COEmlzNdFr/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 2017-04-02 17:01:41,953 truncating revision to first 12 chars 2017-04-02 17:01:41,954 Setting DEBUG logging. 2017-04-02 17:01:41,954 attempt 1/10 2017-04-02 17:01:41,954 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/f0bf3f31114a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-02 17:01:43,413 unpacking tar archive at: mozilla-beta-f0bf3f31114a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.725764 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-02 17:01:43.646829) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-02 17:01:43.647177) ========= script_repo_revision: f0bf3f31114aceba2ab4d71da5686ebc3ae94ea9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-02 17:01:43.647568) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-02 17:01:43.647925) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-02 17:01:43.668065) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2017-04-02 17:01:43.668465) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.E6YrsJP9nX/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.COEmlzNdFr/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 17:01:43 INFO - MultiFileLogger online at 20170402 17:01:43 in /builds/slave/test 17:01:43 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 17:01:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:01:43 INFO - {'append_to_log': False, 17:01:43 INFO - 'base_work_dir': '/builds/slave/test', 17:01:43 INFO - 'blob_upload_branch': 'Mozilla-Beta', 17:01:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:01:43 INFO - 'branch': 'Mozilla-Beta', 17:01:43 INFO - 'buildbot_json_path': 'buildprops.json', 17:01:43 INFO - 'config_files': ('talos/mac_config.py',), 17:01:43 INFO - 'default_actions': ('clobber', 17:01:43 INFO - 'read-buildbot-config', 17:01:43 INFO - 'download-and-extract', 17:01:43 INFO - 'populate-webroot', 17:01:43 INFO - 'create-virtualenv', 17:01:43 INFO - 'install', 17:01:43 INFO - 'run-tests'), 17:01:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:01:43 INFO - 'download_minidump_stackwalk': True, 17:01:43 INFO - 'download_symbols': 'ondemand', 17:01:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:01:43 INFO - 'tooltool.py': '/tools/tooltool.py', 17:01:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:01:43 INFO - '/tools/misc-python/virtualenv.py')}, 17:01:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:01:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:01:43 INFO - 'installer_path': 'installer.exe', 17:01:43 INFO - 'log_level': 'info', 17:01:43 INFO - 'log_name': 'talos', 17:01:43 INFO - 'log_to_console': True, 17:01:43 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:01:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:01:43 INFO - 'opt_config_files': (), 17:01:43 INFO - 'pip_index': False, 17:01:43 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:01:43 INFO - 'cmd': ('bash', 17:01:43 INFO - '-c', 17:01:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:01:43 INFO - 'enabled': True, 17:01:43 INFO - 'halt_on_failure': False, 17:01:43 INFO - 'name': 'check_screen_resolution'},), 17:01:43 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:01:43 INFO - 'cmd': ('bash', 17:01:43 INFO - '-c', 17:01:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:01:43 INFO - 'enabled': True, 17:01:43 INFO - 'halt_on_failure': False, 17:01:43 INFO - 'name': 'check_screen_resolution'},), 17:01:43 INFO - 'run_cmd_checks_enabled': True, 17:01:43 INFO - 'sps_profile': False, 17:01:43 INFO - 'sps_profile_interval': 0, 17:01:43 INFO - 'suite': 'dromaeojs-e10s', 17:01:43 INFO - 'system_bits': '32', 17:01:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:01:43 INFO - 'title': 't-yosemite-r7-0014', 17:01:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:01:43 INFO - 'use_talos_json': True, 17:01:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:01:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:01:43 INFO - 'work_dir': 'build'} 17:01:43 INFO - [mozharness: 2017-04-03 00:01:43.843017Z] Running clobber step. 17:01:43 INFO - Running pre-action listener: _resource_record_pre_action 17:01:43 INFO - Running main action method: clobber 17:01:43 INFO - rmtree: /builds/slave/test/build 17:01:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:01:46 INFO - Running post-action listener: _resource_record_post_action 17:01:46 INFO - [mozharness: 2017-04-03 00:01:46.352580Z] Finished clobber step (success) 17:01:46 INFO - [mozharness: 2017-04-03 00:01:46.352742Z] Running read-buildbot-config step. 17:01:46 INFO - Running pre-action listener: _resource_record_pre_action 17:01:46 INFO - Running main action method: read_buildbot_config 17:01:46 INFO - Using buildbot properties: 17:01:46 INFO - { 17:01:46 INFO - "project": "", 17:01:46 INFO - "product": "firefox", 17:01:46 INFO - "script_repo_revision": "production", 17:01:46 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 17:01:46 INFO - "repository": "", 17:01:46 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 17:01:46 INFO - "buildid": "20170402155023", 17:01:46 INFO - "pgo_build": "False", 17:01:46 INFO - "basedir": "/builds/slave/test", 17:01:46 INFO - "buildnumber": 25, 17:01:46 INFO - "slavename": "t-yosemite-r7-0014", 17:01:46 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 17:01:46 INFO - "platform": "yosemite_r7", 17:01:46 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 17:01:46 INFO - "branch": "mozilla-beta", 17:01:46 INFO - "repo_path": "releases/mozilla-beta", 17:01:46 INFO - "revision": "f0bf3f31114aceba2ab4d71da5686ebc3ae94ea9", 17:01:46 INFO - "stage_platform": "macosx64", 17:01:46 INFO - "builduid": "a344c0cea70f4d90b1521c3973d2e15f", 17:01:46 INFO - "slavebuilddir": "test" 17:01:46 INFO - } 17:01:46 INFO - Found installer url https://queue.taskcluster.net/v1/task/O2qzZRmjQFOtlwqSTXzZyw/artifacts/public/build/firefox-53.0.en-US.mac.dmg. 17:01:46 INFO - Running post-action listener: _resource_record_post_action 17:01:46 INFO - [mozharness: 2017-04-03 00:01:46.356114Z] Finished read-buildbot-config step (success) 17:01:46 INFO - [mozharness: 2017-04-03 00:01:46.356252Z] Running download-and-extract step. 17:01:46 INFO - Running pre-action listener: _resource_record_pre_action 17:01:46 INFO - Running main action method: download_and_extract 17:01:46 INFO - mkdir: /builds/slave/test/build/tests 17:01: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')]} 17:01:46 INFO - trying https://queue.taskcluster.net/v1/task/O2qzZRmjQFOtlwqSTXzZyw/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json 17:01:46 INFO - Downloading https://queue.taskcluster.net/v1/task/O2qzZRmjQFOtlwqSTXzZyw/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json 17:01:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/O2qzZRmjQFOtlwqSTXzZyw/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json'}, attempt #1 17:01:47 INFO - Downloaded 1165 bytes. 17:01:47 INFO - Reading from file /builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json 17:01:47 INFO - Using the following test package requirements: 17:01:47 INFO - {u'common': [u'firefox-53.0.en-US.mac.common.tests.zip'], 17:01:47 INFO - u'cppunittest': [u'firefox-53.0.en-US.mac.common.tests.zip', 17:01:47 INFO - u'firefox-53.0.en-US.mac.cppunittest.tests.zip'], 17:01:47 INFO - u'gtest': [u'firefox-53.0.en-US.mac.common.tests.zip', 17:01:47 INFO - u'firefox-53.0.en-US.mac.gtest.tests.zip'], 17:01:47 INFO - u'jittest': [u'firefox-53.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 17:01:47 INFO - u'mochitest': [u'firefox-53.0.en-US.mac.common.tests.zip', 17:01:47 INFO - u'firefox-53.0.en-US.mac.mochitest.tests.zip'], 17:01:47 INFO - u'mozbase': [u'firefox-53.0.en-US.mac.common.tests.zip'], 17:01:47 INFO - u'reftest': [u'firefox-53.0.en-US.mac.common.tests.zip', 17:01:47 INFO - u'firefox-53.0.en-US.mac.reftest.tests.zip'], 17:01:47 INFO - u'talos': [u'firefox-53.0.en-US.mac.common.tests.zip', 17:01:47 INFO - u'firefox-53.0.en-US.mac.talos.tests.zip'], 17:01:47 INFO - u'web-platform': [u'firefox-53.0.en-US.mac.common.tests.zip', 17:01:47 INFO - u'firefox-53.0.en-US.mac.web-platform.tests.zip'], 17:01:47 INFO - u'xpcshell': [u'firefox-53.0.en-US.mac.common.tests.zip', 17:01:47 INFO - u'firefox-53.0.en-US.mac.xpcshell.tests.zip']} 17:01:47 INFO - Downloading packages: [u'firefox-53.0.en-US.mac.common.tests.zip'] for test suite category: common 17:01:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/O2qzZRmjQFOtlwqSTXzZyw/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip 17:01:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/O2qzZRmjQFOtlwqSTXzZyw/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip'}, attempt #1 17:01:47 INFO - Fetch https://queue.taskcluster.net/v1/task/O2qzZRmjQFOtlwqSTXzZyw/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip into memory 17:01:48 INFO - Expected file size: 16109790 17:01:48 INFO - Obtained file size: 16109790 17:01:54 INFO - Downloading packages: [u'firefox-53.0.en-US.mac.common.tests.zip', u'firefox-53.0.en-US.mac.talos.tests.zip'] for test suite category: talos 17:01:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/O2qzZRmjQFOtlwqSTXzZyw/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip 17:01:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/O2qzZRmjQFOtlwqSTXzZyw/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip'}, attempt #1 17:01:54 INFO - Fetch https://queue.taskcluster.net/v1/task/O2qzZRmjQFOtlwqSTXzZyw/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip into memory 17:01:55 INFO - Expected file size: 16109790 17:01:55 INFO - Obtained file size: 16109790 17:02:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/O2qzZRmjQFOtlwqSTXzZyw/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip 17:02:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/O2qzZRmjQFOtlwqSTXzZyw/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip'}, attempt #1 17:02:02 INFO - Fetch https://queue.taskcluster.net/v1/task/O2qzZRmjQFOtlwqSTXzZyw/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip into memory 17:02:03 INFO - Expected file size: 14403663 17:02:03 INFO - Obtained file size: 14403663 17:02:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:02:03 INFO - trying https://queue.taskcluster.net/v1/task/O2qzZRmjQFOtlwqSTXzZyw/artifacts/public/build/firefox-53.0.en-US.mac.dmg 17:02:03 INFO - Downloading https://queue.taskcluster.net/v1/task/O2qzZRmjQFOtlwqSTXzZyw/artifacts/public/build/firefox-53.0.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0.en-US.mac.dmg 17:02:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/O2qzZRmjQFOtlwqSTXzZyw/artifacts/public/build/firefox-53.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0.en-US.mac.dmg'}, attempt #1 17:02:06 INFO - Downloaded 58726916 bytes. 17:02:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/O2qzZRmjQFOtlwqSTXzZyw/artifacts/public/build/firefox-53.0.en-US.mac.dmg 17:02:06 INFO - mkdir: /builds/slave/test/properties 17:02:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:02:06 INFO - Writing to file /builds/slave/test/properties/build_url 17:02:06 INFO - Contents: 17:02:06 INFO - build_url:https://queue.taskcluster.net/v1/task/O2qzZRmjQFOtlwqSTXzZyw/artifacts/public/build/firefox-53.0.en-US.mac.dmg 17:02:06 INFO - Running post-action listener: _resource_record_post_action 17:02:06 INFO - Running post-action listener: set_extra_try_arguments 17:02:06 INFO - [mozharness: 2017-04-03 00:02:06.737179Z] Finished download-and-extract step (success) 17:02:06 INFO - [mozharness: 2017-04-03 00:02:06.737315Z] Running populate-webroot step. 17:02:06 INFO - Running pre-action listener: _resource_record_pre_action 17:02:06 INFO - Running main action method: populate_webroot 17:02:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:02:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:02:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:02:06 INFO - '--tppagecycles', 17:02:06 INFO - '7'], 17:02:06 INFO - 'tests': ['tsvgm']}}, 17:02:06 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:02:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:02:06 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:02:06 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:02:06 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:02:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:02:06 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 17:02:06 INFO - 'talos_options': ['--disable-e10s'], 17:02:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:02:06 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:02:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:02:06 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 17:02:06 INFO - 'talos_options': ['--disable-e10s'], 17:02:06 INFO - 'tests': ['damp', 'tps']}, 17:02:06 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:02:06 INFO - 'tests': ['damp', 'tps']}, 17:02:06 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:02:06 INFO - 'tests': ['dromaeo_dom']}, 17:02:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:02:06 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:02:06 INFO - 'tests': ['basic_compositor_video']}, 17:02:06 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:02:06 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:02:06 INFO - 'tests': ['a11yr', 17:02:06 INFO - 'ts_paint', 17:02:06 INFO - 'tpaint', 17:02:06 INFO - 'sessionrestore', 17:02:06 INFO - 'sessionrestore_no_auto_restore', 17:02:06 INFO - 'tabpaint']}, 17:02:06 INFO - 'other-e10s': {'tests': ['a11yr', 17:02:06 INFO - 'ts_paint', 17:02:06 INFO - 'tpaint', 17:02:06 INFO - 'sessionrestore', 17:02:06 INFO - 'sessionrestore_no_auto_restore', 17:02:06 INFO - 'tabpaint']}, 17:02:06 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:02:06 INFO - 'ts_paint', 17:02:06 INFO - 'tpaint', 17:02:06 INFO - 'sessionrestore', 17:02:06 INFO - 'sessionrestore_no_auto_restore', 17:02:06 INFO - 'tabpaint']}, 17:02:06 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:02:06 INFO - 'ts_paint', 17:02:06 INFO - 'tpaint', 17:02:06 INFO - 'sessionrestore', 17:02:06 INFO - 'sessionrestore_no_auto_restore', 17:02:06 INFO - 'tabpaint']}, 17:02:06 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:02:06 INFO - 'tests': ['a11yr', 17:02:06 INFO - 'ts_paint', 17:02:06 INFO - 'tpaint', 17:02:06 INFO - 'sessionrestore', 17:02:06 INFO - 'sessionrestore_no_auto_restore', 17:02:06 INFO - 'tabpaint']}, 17:02:06 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:02:06 INFO - 'tests': ['a11yr', 17:02:06 INFO - 'ts_paint', 17:02:06 INFO - 'tpaint', 17:02:06 INFO - 'sessionrestore', 17:02:06 INFO - 'sessionrestore_no_auto_restore', 17:02:06 INFO - 'tabpaint']}, 17:02:06 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:02:06 INFO - 'tests': ['tsvgx', 17:02:06 INFO - 'tsvgr_opacity', 17:02:06 INFO - 'tart', 17:02:06 INFO - 'tscrollx', 17:02:06 INFO - 'cart', 17:02:06 INFO - 'tsvg_static']}, 17:02:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:02:06 INFO - 'tsvgr_opacity', 17:02:06 INFO - 'tart', 17:02:06 INFO - 'tscrollx', 17:02:06 INFO - 'cart', 17:02:06 INFO - 'tsvg_static']}, 17:02:06 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 17:02:06 INFO - 'talos_options': ['--disable-e10s'], 17:02:06 INFO - 'tests': ['tp5o']}, 17:02:06 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:02:06 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 17:02:06 INFO - 'talos_options': ['--disable-e10s', 17:02:06 INFO - '--xperf_path', 17:02:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:02:06 INFO - 'tests': ['tp5n']}, 17:02:06 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:02:06 INFO - 'talos_options': ['--xperf_path', 17:02:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:02:06 INFO - 'tests': ['tp5n']}}, 17:02:06 INFO - 'talos.zip': {'path': '', 17:02:06 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:02:06 INFO - Running post-action listener: _resource_record_post_action 17:02:06 INFO - [mozharness: 2017-04-03 00:02:06.755707Z] Finished populate-webroot step (success) 17:02:06 INFO - [mozharness: 2017-04-03 00:02:06.755836Z] Running create-virtualenv step. 17:02:06 INFO - Running pre-action listener: _resource_record_pre_action 17:02:06 INFO - Running main action method: create_virtualenv 17:02:06 INFO - Creating virtualenv /builds/slave/test/build/venv 17:02:06 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 17:02:06 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:02:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:02:06 INFO - Using real prefix '/tools/python27' 17:02:06 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:02:07 INFO - Installing distribute.............................................................................................................................................................................................done. 17:02:10 INFO - Installing pip.................done. 17:02:10 INFO - Return code: 0 17:02:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:02:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:02:10 INFO - Reading from file tmpfile_stdout 17:02:10 INFO - Output received: 17:02:10 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 17:02:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:02:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:02:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:02:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:02:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:02:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:10 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 0x1014bdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014c0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa710d779b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10129bd30>, '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 0x1011c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101290be8>, '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.E6YrsJP9nX/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.COEmlzNdFr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 17:02:10 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 17:02:10 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 17:02:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.E6YrsJP9nX/Render', 17:02:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:02:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:02:10 INFO - 'HOME': '/Users/cltbld', 17:02:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:02:10 INFO - 'LOGNAME': 'cltbld', 17:02:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:02:10 INFO - 'MOZ_NO_REMOTE': '1', 17:02:10 INFO - 'NO_EM_RESTART': '1', 17:02:10 INFO - 'PAGER': '/bin/cat', 17:02:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:02:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:02:10 INFO - 'PWD': '/builds/slave/test', 17:02:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:02:10 INFO - 'SHELL': '/bin/bash', 17:02:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.COEmlzNdFr/Listeners', 17:02:10 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 17:02:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:02:10 INFO - 'USER': 'cltbld', 17:02:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:02:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:02:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:02:10 INFO - 'XPC_FLAGS': '0x0', 17:02:10 INFO - 'XPC_SERVICE_NAME': '0', 17:02:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:02:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:02:10 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 17:02:10 INFO - Cleaning up... 17:02:10 INFO - Return code: 0 17:02:10 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:02:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:02:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:02:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:02:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:02:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:10 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 0x1014bdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014c0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa710d779b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10129bd30>, '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 0x1011c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101290be8>, '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.E6YrsJP9nX/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.COEmlzNdFr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 17:02:10 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 17:02:10 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 17:02:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.E6YrsJP9nX/Render', 17:02:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:02:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:02:10 INFO - 'HOME': '/Users/cltbld', 17:02:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:02:10 INFO - 'LOGNAME': 'cltbld', 17:02:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:02:10 INFO - 'MOZ_NO_REMOTE': '1', 17:02:10 INFO - 'NO_EM_RESTART': '1', 17:02:10 INFO - 'PAGER': '/bin/cat', 17:02:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:02:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:02:10 INFO - 'PWD': '/builds/slave/test', 17:02:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:02:10 INFO - 'SHELL': '/bin/bash', 17:02:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.COEmlzNdFr/Listeners', 17:02:10 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 17:02:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:02:10 INFO - 'USER': 'cltbld', 17:02:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:02:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:02:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:02:10 INFO - 'XPC_FLAGS': '0x0', 17:02:10 INFO - 'XPC_SERVICE_NAME': '0', 17:02:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:02:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:02:11 INFO - Downloading/unpacking psutil>=3.1.1 17:02:11 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 17:02:11 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 17:02:11 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 17:02:11 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 17:02:11 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 17:02:11 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 17:02:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:02:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:02:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:02:14 INFO - Installing collected packages: psutil 17:02:14 INFO - Running setup.py install for psutil 17:02:14 INFO - building 'psutil._psutil_osx' extension 17:02:14 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 17:02:15 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 17:02:15 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 17:02:15 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 17:02:15 INFO - building 'psutil._psutil_posix' extension 17:02:15 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 17:02:15 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:02:15 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:02:15 INFO - ^ 17:02:15 INFO - 1 warning generated. 17:02:15 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 17:02:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:02:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:02:16 INFO - Successfully installed psutil 17:02:16 INFO - Cleaning up... 17:02:16 INFO - Return code: 0 17:02:16 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:02:16 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')]} 17:02:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:02:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:16 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')]} 17:02:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:02:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:16 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 0x1014bdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014c0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa710d779b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10129bd30>, '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 0x1011c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101290be8>, '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.E6YrsJP9nX/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.COEmlzNdFr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 17:02:16 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 17:02:16 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 17:02:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.E6YrsJP9nX/Render', 17:02:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:02:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:02:16 INFO - 'HOME': '/Users/cltbld', 17:02:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:02:16 INFO - 'LOGNAME': 'cltbld', 17:02:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:02:16 INFO - 'MOZ_NO_REMOTE': '1', 17:02:16 INFO - 'NO_EM_RESTART': '1', 17:02:16 INFO - 'PAGER': '/bin/cat', 17:02:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:02:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:02:16 INFO - 'PWD': '/builds/slave/test', 17:02:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:02:16 INFO - 'SHELL': '/bin/bash', 17:02:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.COEmlzNdFr/Listeners', 17:02:16 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 17:02:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:02:16 INFO - 'USER': 'cltbld', 17:02:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:02:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:02:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:02:16 INFO - 'XPC_FLAGS': '0x0', 17:02:16 INFO - 'XPC_SERVICE_NAME': '0', 17:02:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:02:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:02:16 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:02:16 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 17:02:16 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 17:02:16 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 17:02:16 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 17:02:16 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 17:02:16 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 17:02:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:02:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:02:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:02:19 INFO - Installing collected packages: mozsystemmonitor 17:02:19 INFO - Running setup.py install for mozsystemmonitor 17:02:19 INFO - Successfully installed mozsystemmonitor 17:02:19 INFO - Cleaning up... 17:02:19 INFO - Return code: 0 17:02:19 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:02:19 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')]} 17:02:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:02:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:19 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')]} 17:02:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:02:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:19 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 0x1014bdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014c0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa710d779b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10129bd30>, '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 0x1011c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101290be8>, '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.E6YrsJP9nX/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.COEmlzNdFr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 17:02:19 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 17:02:19 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 17:02:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.E6YrsJP9nX/Render', 17:02:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:02:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:02:19 INFO - 'HOME': '/Users/cltbld', 17:02:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:02:19 INFO - 'LOGNAME': 'cltbld', 17:02:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:02:19 INFO - 'MOZ_NO_REMOTE': '1', 17:02:19 INFO - 'NO_EM_RESTART': '1', 17:02:19 INFO - 'PAGER': '/bin/cat', 17:02:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:02:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:02:19 INFO - 'PWD': '/builds/slave/test', 17:02:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:02:19 INFO - 'SHELL': '/bin/bash', 17:02:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.COEmlzNdFr/Listeners', 17:02:19 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 17:02:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:02:19 INFO - 'USER': 'cltbld', 17:02:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:02:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:02:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:02:19 INFO - 'XPC_FLAGS': '0x0', 17:02:19 INFO - 'XPC_SERVICE_NAME': '0', 17:02:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:02:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:02:19 INFO - Downloading/unpacking jsonschema==2.5.1 17:02:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:02:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:02:19 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 17:02:19 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 17:02:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:02:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:02:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:02:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:02:22 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 17:02:22 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 17:02:22 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 17:02:22 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 17:02:22 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 17:02:22 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 17:02:22 INFO - Downloading functools32-3.2.3-2.tar.gz 17:02:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:02:22 INFO - warning: no files found matching '*.txt' 17:02:22 INFO - no previously-included directories found matching 'build' 17:02:22 INFO - no previously-included directories found matching 'dist' 17:02:22 INFO - no previously-included directories found matching '.git*' 17:02:22 INFO - Installing collected packages: jsonschema, functools32 17:02:22 INFO - Running setup.py install for functools32 17:02:22 INFO - warning: no files found matching '*.txt' 17:02:22 INFO - no previously-included directories found matching 'build' 17:02:22 INFO - no previously-included directories found matching 'dist' 17:02:22 INFO - no previously-included directories found matching '.git*' 17:02:22 INFO - Successfully installed jsonschema functools32 17:02:22 INFO - Cleaning up... 17:02:22 INFO - Return code: 0 17:02:22 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:02:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:02:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:02:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:02:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:02:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:22 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 0x1014bdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014c0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa710d779b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10129bd30>, '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 0x1011c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101290be8>, '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.E6YrsJP9nX/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.COEmlzNdFr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 17:02:22 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 17:02:22 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 17:02:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.E6YrsJP9nX/Render', 17:02:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:02:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:02:22 INFO - 'HOME': '/Users/cltbld', 17:02:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:02:22 INFO - 'LOGNAME': 'cltbld', 17:02:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:02:22 INFO - 'MOZ_NO_REMOTE': '1', 17:02:22 INFO - 'NO_EM_RESTART': '1', 17:02:22 INFO - 'PAGER': '/bin/cat', 17:02:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:02:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:02:22 INFO - 'PWD': '/builds/slave/test', 17:02:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:02:22 INFO - 'SHELL': '/bin/bash', 17:02:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.COEmlzNdFr/Listeners', 17:02:22 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 17:02:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:02:22 INFO - 'USER': 'cltbld', 17:02:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:02:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:02:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:02:22 INFO - 'XPC_FLAGS': '0x0', 17:02:22 INFO - 'XPC_SERVICE_NAME': '0', 17:02:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:02:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:02:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:02:23 INFO - Cleaning up... 17:02:23 INFO - Return code: 0 17:02:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:02:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:02:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:02:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:02:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:02:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1014bdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014c0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa710d779b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10129bd30>, '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 0x1011c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101290be8>, '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.E6YrsJP9nX/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.COEmlzNdFr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 17:02:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:02:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 17:02:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.E6YrsJP9nX/Render', 17:02:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:02:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:02:23 INFO - 'HOME': '/Users/cltbld', 17:02:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:02:23 INFO - 'LOGNAME': 'cltbld', 17:02:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:02:23 INFO - 'MOZ_NO_REMOTE': '1', 17:02:23 INFO - 'NO_EM_RESTART': '1', 17:02:23 INFO - 'PAGER': '/bin/cat', 17:02:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:02:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:02:23 INFO - 'PWD': '/builds/slave/test', 17:02:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:02:23 INFO - 'SHELL': '/bin/bash', 17:02:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.COEmlzNdFr/Listeners', 17:02:23 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 17:02:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:02:23 INFO - 'USER': 'cltbld', 17:02:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:02:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:02:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:02:23 INFO - 'XPC_FLAGS': '0x0', 17:02:23 INFO - 'XPC_SERVICE_NAME': '0', 17:02:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:02:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:02:23 INFO - Downloading/unpacking blobuploader==1.2.4 17:02:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:02:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:02:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:02:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:02:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:02:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:02:25 INFO - Downloading blobuploader-1.2.4.tar.gz 17:02:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:02:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:02: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 17:02: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 17:02: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 17:02: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 17:02: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 17:02: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 17:02:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:02:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:02: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 17:02: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 17:02: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 17:02: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 17:02: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 17:02: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 17:02:27 INFO - Downloading docopt-0.6.1.tar.gz 17:02:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:02:27 INFO - Installing collected packages: blobuploader, requests, docopt 17:02:27 INFO - Running setup.py install for blobuploader 17:02:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:02:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:02:27 INFO - Running setup.py install for requests 17:02:27 INFO - Running setup.py install for docopt 17:02:28 INFO - Successfully installed blobuploader requests docopt 17:02:28 INFO - Cleaning up... 17:02:28 INFO - Return code: 0 17:02:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:02:28 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')]} 17:02:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:02:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:28 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')]} 17:02:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:02:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:28 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 0x1014bdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014c0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa710d779b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10129bd30>, '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 0x1011c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101290be8>, '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.E6YrsJP9nX/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.COEmlzNdFr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 17:02:28 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 17:02:28 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 17:02:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.E6YrsJP9nX/Render', 17:02:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:02:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:02:28 INFO - 'HOME': '/Users/cltbld', 17:02:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:02:28 INFO - 'LOGNAME': 'cltbld', 17:02:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:02:28 INFO - 'MOZ_NO_REMOTE': '1', 17:02:28 INFO - 'NO_EM_RESTART': '1', 17:02:28 INFO - 'PAGER': '/bin/cat', 17:02:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:02:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:02:28 INFO - 'PWD': '/builds/slave/test', 17:02:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:02:28 INFO - 'SHELL': '/bin/bash', 17:02:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.COEmlzNdFr/Listeners', 17:02:28 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 17:02:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:02:28 INFO - 'USER': 'cltbld', 17:02:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:02:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:02:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:02:28 INFO - 'XPC_FLAGS': '0x0', 17:02:28 INFO - 'XPC_SERVICE_NAME': '0', 17:02:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:02:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:02:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:02:28 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-o9f6LD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:02:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:02:28 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-1tL80J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:02:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:02:28 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-QPvwz6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:02:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:02:28 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-9xRhYE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:02:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:02:28 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-PIRQEl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:02:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:02:29 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-t4nxqL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:02:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:02:29 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-j5d4BM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:02:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:02:29 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-Y7yzf3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:02:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:02:29 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-euchnR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:02:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:02:29 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-WHXgYV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:02:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:02:29 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-mhneRE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:02:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:02:29 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-yiHCJM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:02:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:02:29 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-ev6AYa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:02:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:02:29 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-BGOOiR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:02:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:02:29 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-GSO0zS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:02:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:02:30 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-nY6RAm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:02:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:02:30 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-aKvtnM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:02:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:02:30 INFO - Running setup.py install for manifestparser 17:02:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:02:30 INFO - Running setup.py install for mozcrash 17:02:30 INFO - Running setup.py install for mozdebug 17:02:30 INFO - Running setup.py install for mozdevice 17:02:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:02:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:02:30 INFO - Running setup.py install for mozfile 17:02:30 INFO - Running setup.py install for mozhttpd 17:02:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:02:31 INFO - Running setup.py install for mozinfo 17:02:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:02:31 INFO - Running setup.py install for mozInstall 17:02:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:02:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:02:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:02:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:02:31 INFO - Running setup.py install for mozleak 17:02:31 INFO - Running setup.py install for mozlog 17:02:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:02:31 INFO - Running setup.py install for moznetwork 17:02:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:02:31 INFO - Running setup.py install for mozprocess 17:02:32 INFO - Running setup.py install for mozprofile 17:02:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:02:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:02:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:02:32 INFO - Running setup.py install for mozrunner 17:02:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:02:32 INFO - Running setup.py install for mozscreenshot 17:02:32 INFO - Running setup.py install for moztest 17:02:32 INFO - Running setup.py install for mozversion 17:02:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:02:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:02:32 INFO - Cleaning up... 17:02:32 INFO - Return code: 0 17:02:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:02:32 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')]} 17:02:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:02:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:32 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')]} 17:02:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:02:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:32 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 0x1014bdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014c0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa710d779b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10129bd30>, '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 0x1011c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101290be8>, '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.E6YrsJP9nX/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.COEmlzNdFr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 17:02:32 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 17:02:32 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 17:02:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.E6YrsJP9nX/Render', 17:02:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:02:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:02:32 INFO - 'HOME': '/Users/cltbld', 17:02:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:02:32 INFO - 'LOGNAME': 'cltbld', 17:02:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:02:32 INFO - 'MOZ_NO_REMOTE': '1', 17:02:32 INFO - 'NO_EM_RESTART': '1', 17:02:32 INFO - 'PAGER': '/bin/cat', 17:02:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:02:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:02:32 INFO - 'PWD': '/builds/slave/test', 17:02:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:02:32 INFO - 'SHELL': '/bin/bash', 17:02:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.COEmlzNdFr/Listeners', 17:02:32 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 17:02:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:02:32 INFO - 'USER': 'cltbld', 17:02:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:02:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:02:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:02:32 INFO - 'XPC_FLAGS': '0x0', 17:02:32 INFO - 'XPC_SERVICE_NAME': '0', 17:02:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:02:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:02:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:02:33 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-ni4zM_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:02:33 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)) 17:02:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:02:33 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-5zoHhl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:02:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 17:02:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:02:33 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-6bCFTb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:02:33 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)) 17:02:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:02:33 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-3jZL75-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:02:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 17:02:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:02:33 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-bQJS3a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:02:33 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)) 17:02:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:02:33 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-im97u7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:02:33 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)) 17:02:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:02:33 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-XvZfHB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:02:33 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)) 17:02:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:02:33 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-DeuEwg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:02:33 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)) 17:02:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:02:33 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-AAztar-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:02:34 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)) 17:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:02:34 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-pZZhwx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:02:34 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)) 17:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:02:34 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-oUbPdD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:02:34 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)) 17:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:02:34 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-l3j760-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:02:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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)) 17:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:02:34 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-dD4cjt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:02:34 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)) 17:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:02:34 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-d5n1g_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:02:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 17:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:02:34 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-u0T9ka-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:02:34 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)) 17:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:02:34 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-UltvRv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:02:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 17:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:02:34 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-cc_t58-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:02:34 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)) 17:02:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:02:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:02:34 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)) 17:02:34 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:02:34 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:02:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:02:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:02:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:02:34 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 17:02:34 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 17:02:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:02:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:02:37 INFO - Downloading blessings-1.6.tar.gz 17:02:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:02:37 INFO - Installing collected packages: blessings 17:02:37 INFO - Running setup.py install for blessings 17:02:37 INFO - Successfully installed blessings 17:02:37 INFO - Cleaning up... 17:02:37 INFO - Return code: 0 17:02:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:02:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:02:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:02:38 INFO - Reading from file tmpfile_stdout 17:02:38 INFO - Current package versions: 17:02:38 INFO - blessings == 1.6 17:02:38 INFO - blobuploader == 1.2.4 17:02:38 INFO - docopt == 0.6.1 17:02:38 INFO - functools32 == 3.2.3-2 17:02:38 INFO - jsonschema == 2.5.1 17:02:38 INFO - manifestparser == 1.1 17:02:38 INFO - mozInstall == 1.12 17:02:38 INFO - mozcrash == 1.0 17:02:38 INFO - mozdebug == 0.1 17:02:38 INFO - mozdevice == 0.49 17:02:38 INFO - mozfile == 1.2 17:02:38 INFO - mozhttpd == 0.7 17:02:38 INFO - mozinfo == 0.9 17:02:38 INFO - mozleak == 0.1 17:02:38 INFO - mozlog == 3.4 17:02:38 INFO - moznetwork == 0.27 17:02:38 INFO - mozprocess == 0.24 17:02:38 INFO - mozprofile == 0.28 17:02:38 INFO - mozrunner == 6.13 17:02:38 INFO - mozscreenshot == 0.1 17:02:38 INFO - mozsystemmonitor == 0.3 17:02:38 INFO - moztest == 0.8 17:02:38 INFO - mozversion == 1.4 17:02:38 INFO - psutil == 3.1.1 17:02:38 INFO - requests == 1.2.3 17:02:38 INFO - wsgiref == 0.1.2 17:02:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:02:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:02:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:02:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:02:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:02:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:39 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 0x1014bdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014c0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa710d779b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10129bd30>, '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 0x1011c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101290be8>, '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.E6YrsJP9nX/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.COEmlzNdFr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 17:02:39 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 17:02:39 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 17:02:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.E6YrsJP9nX/Render', 17:02:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:02:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:02:39 INFO - 'HOME': '/Users/cltbld', 17:02:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:02:39 INFO - 'LOGNAME': 'cltbld', 17:02:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:02:39 INFO - 'MOZ_NO_REMOTE': '1', 17:02:39 INFO - 'NO_EM_RESTART': '1', 17:02:39 INFO - 'PAGER': '/bin/cat', 17:02:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:02:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:02:39 INFO - 'PWD': '/builds/slave/test', 17:02:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:02:39 INFO - 'SHELL': '/bin/bash', 17:02:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.COEmlzNdFr/Listeners', 17:02:39 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 17:02:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:02:39 INFO - 'USER': 'cltbld', 17:02:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:02:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:02:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:02:39 INFO - 'XPC_FLAGS': '0x0', 17:02:39 INFO - 'XPC_SERVICE_NAME': '0', 17:02:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:02:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:02:39 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)) 17:02:39 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)) 17:02:39 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)) 17:02:39 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)) 17:02:39 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)) 17:02:39 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)) 17:02:39 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)) 17:02:39 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)) 17:02:39 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)) 17:02:39 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)) 17:02:39 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)) 17:02:39 INFO - Cleaning up... 17:02:39 INFO - Return code: 0 17:02:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:02:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:02:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:02:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:02:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:02:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:39 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 0x1014bdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014c0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa710d779b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10129bd30>, '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 0x1011c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101290be8>, '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.E6YrsJP9nX/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.COEmlzNdFr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 17:02:39 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 17:02:39 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 17:02:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.E6YrsJP9nX/Render', 17:02:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:02:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:02:39 INFO - 'HOME': '/Users/cltbld', 17:02:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:02:39 INFO - 'LOGNAME': 'cltbld', 17:02:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:02:39 INFO - 'MOZ_NO_REMOTE': '1', 17:02:39 INFO - 'NO_EM_RESTART': '1', 17:02:39 INFO - 'PAGER': '/bin/cat', 17:02:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:02:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:02:39 INFO - 'PWD': '/builds/slave/test', 17:02:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:02:39 INFO - 'SHELL': '/bin/bash', 17:02:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.COEmlzNdFr/Listeners', 17:02:39 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 17:02:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:02:39 INFO - 'USER': 'cltbld', 17:02:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:02:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:02:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:02:39 INFO - 'XPC_FLAGS': '0x0', 17:02:39 INFO - 'XPC_SERVICE_NAME': '0', 17:02:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:02:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:02:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:02:40 INFO - Cleaning up... 17:02:40 INFO - Return code: 0 17:02:40 INFO - Running post-action listener: _resource_record_post_action 17:02:40 INFO - Running post-action listener: _start_resource_monitoring 17:02:40 INFO - Starting resource monitoring. 17:02:40 INFO - [mozharness: 2017-04-03 00:02:40.215528Z] Finished create-virtualenv step (success) 17:02:40 INFO - [mozharness: 2017-04-03 00:02:40.216462Z] Running install step. 17:02:40 INFO - Running pre-action listener: _resource_record_pre_action 17:02:40 INFO - Running main action method: install 17:02:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:02:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:02:40 INFO - Reading from file tmpfile_stdout 17:02:40 INFO - Detecting whether we're running mozinstall >=1.0... 17:02:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:02:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:02:40 INFO - Reading from file tmpfile_stdout 17:02:40 INFO - Output received: 17:02:40 INFO - Usage: mozinstall [options] installer 17:02:40 INFO - Options: 17:02:40 INFO - -h, --help show this help message and exit 17:02:40 INFO - -d DEST, --destination=DEST 17:02:40 INFO - Directory to install application into. [default: 17:02:40 INFO - "/builds/slave/test"] 17:02:40 INFO - --app=APP Application being installed. [default: firefox] 17:02:40 INFO - mkdir: /builds/slave/test/build/application 17:02:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 17:02:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0.en-US.mac.dmg --destination /builds/slave/test/build/application 17:02:55 INFO - Reading from file tmpfile_stdout 17:02:55 INFO - Output received: 17:02:55 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 17:02:55 INFO - Running post-action listener: _resource_record_post_action 17:02:55 INFO - [mozharness: 2017-04-03 00:02:55.195952Z] Finished install step (success) 17:02:55 INFO - [mozharness: 2017-04-03 00:02:55.196062Z] Running run-tests step. 17:02:55 INFO - Running pre-action listener: _resource_record_pre_action 17:02:55 INFO - Running main action method: run_tests 17:02:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:02:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:02:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:02:55 INFO - 2017-04-02 17:02:55.209 screenresolution[1630:7505] starting screenresolution argv=screenresolution get 17:02:55 INFO - 2017-04-02 17:02:55.212 screenresolution[1630:7505] Display 0: 1600x1200x32@60 17:02:55 INFO - 2017-04-02 17:02:55.217 screenresolution[1631:7507] starting screenresolution argv=screenresolution list 17:02:55 INFO - Available Modes on Display 0 17:02:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:02:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:02:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:02:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:02:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:02:55 INFO - Intel Iris: 17:02:55 INFO - Chipset Model: Intel Iris 17:02:55 INFO - Type: GPU 17:02:55 INFO - Bus: Built-In 17:02:55 INFO - VRAM (Dynamic, Max): 1536 MB 17:02:55 INFO - Vendor: Intel (0x8086) 17:02:55 INFO - Device ID: 0x0a2e 17:02:55 INFO - Revision ID: 0x0009 17:02:55 INFO - Displays: 17:02:55 INFO - Display: 17:02:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:02:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:02:55 INFO - Display Serial Number: 1600x1200 60 17:02:55 INFO - Main Display: Yes 17:02:55 INFO - Mirror: Off 17:02:55 INFO - Online: Yes 17:02:55 INFO - Rotation: Supported 17:02:55 INFO - Return code: 0 17:02:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:02:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:02:55 INFO - Python 2.7.3 17:02:55 INFO - Return code: 0 17:02:55 INFO - grabbing minidump binary from tooltool 17:02:55 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')]} 17:02:55 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 0x10129bd30>, '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 0x1011c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101290be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:02:55 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 17:02:55 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 17:02:55 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 17:02:55 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:02:55 INFO - Return code: 0 17:02:55 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:02:55 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:02:55 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:02:55 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:02:55 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:02:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/O2qzZRmjQFOtlwqSTXzZyw/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0014', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 17:02:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/O2qzZRmjQFOtlwqSTXzZyw/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0014 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 17:02:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.E6YrsJP9nX/Render', 17:02:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:02:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:02:55 INFO - 'HOME': '/Users/cltbld', 17:02:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:02:55 INFO - 'LOGNAME': 'cltbld', 17:02:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:02:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:02:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:02:55 INFO - 'MOZ_NO_REMOTE': '1', 17:02:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:02:55 INFO - 'NO_EM_RESTART': '1', 17:02:55 INFO - 'PAGER': '/bin/cat', 17:02:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:02:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:02:55 INFO - 'PWD': '/builds/slave/test', 17:02:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:02:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:02:55 INFO - 'SHELL': '/bin/bash', 17:02:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.COEmlzNdFr/Listeners', 17:02:55 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 17:02:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:02:55 INFO - 'USER': 'cltbld', 17:02:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:02:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:02:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:02:55 INFO - 'XPC_FLAGS': '0x0', 17:02:55 INFO - 'XPC_SERVICE_NAME': '0', 17:02:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:02:55 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/O2qzZRmjQFOtlwqSTXzZyw/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0014', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 17:02:55 INFO - mozversion application_buildid: 20170402155023 17:02:55 INFO - mozversion application_changeset: f0bf3f31114aceba2ab4d71da5686ebc3ae94ea9 17:02:55 INFO - mozversion application_display_name: Firefox 17:02:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:02:55 INFO - mozversion application_name: Firefox 17:02:55 INFO - mozversion application_remotingname: firefox 17:02:55 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 17:02:55 INFO - mozversion application_vendor: Mozilla 17:02:55 INFO - mozversion application_version: 53.0 17:02:55 INFO - mozversion platform_buildid: 20170402155023 17:02:55 INFO - mozversion platform_changeset: f0bf3f31114aceba2ab4d71da5686ebc3ae94ea9 17:02:55 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 17:02:55 INFO - mozversion platform_version: 53.0 17:02:55 INFO - using testdate: 1491177775 17:02:55 INFO - actual date: 1491177775 17:02:55 INFO - starting webserver on 'localhost:49248' 17:02:55 INFO - SUITE-START | Running 2 tests 17:02:55 INFO - TEST-START | dromaeo_css 17:02:55 INFO - operating with platform_type : mac_ 17:02:55 INFO - Initialising browser for dromaeo_css test... 17:02:55 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/tmpsaqeJ6/profile http://localhost:49248/getInfo.html) 17:02:58 INFO - PROCESS | 1641 | 2017-04-02 17:02:58.731 plugin-container[1644:7680] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d3b, name = 'com.apple.tsm.portname' 17:02:58 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:02:58 INFO - PROCESS | 1641 | 2017-04-02 17:02:58.732 plugin-container[1644:7680] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4d1f, name = 'com.apple.CFPasteboardClient' 17:02:58 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:02:58 INFO - PROCESS | 1641 | 2017-04-02 17:02:58.732 plugin-container[1644:7680] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:02:58 INFO - PROCESS | 1641 | __metrics Screen width/height:1600/1200 17:02:58 INFO - PROCESS | 1641 | colorDepth:24 17:02:58 INFO - PROCESS | 1641 | Browser inner width/height: 1024/689 17:02:58 INFO - PROCESS | 1641 | __metrics 17:02:58 ERROR - PROCESS | 1641 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 17:02:59 INFO - TEST-INFO | 1641: exit 0 17:02:59 INFO - Browser initialized. 17:02:59 INFO - Running cycle 1/1 for dromaeo_css test... 17:02:59 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/tmpsaqeJ6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:03:01 INFO - PROCESS | 1645 | 2017-04-02 17:03:01.989 plugin-container[1646:7799] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4247, name = 'com.apple.tsm.portname' 17:03:01 INFO - PROCESS | 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:03:01 INFO - PROCESS | 1645 | 2017-04-02 17:03:01.989 plugin-container[1646:7799] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x451b, name = 'com.apple.CFPasteboardClient' 17:03:01 INFO - PROCESS | 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:03:01 INFO - PROCESS | 1645 | 2017-04-02 17:03:01.989 plugin-container[1646:7799] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:03:11 INFO - PROCESS | 1645 | RSS: Main: 173166592 17:03:11 INFO - PROCESS | 1645 | 17:06:13 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49248/tests/dromaeo/cssquery-ext.html) 17:06:15 INFO - PROCESS | 1645 | RSS: Main: 194166784 17:06:15 INFO - PROCESS | 1645 | 17:09:18 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-ext.html (next: http://localhost:49248/tests/dromaeo/cssquery-jquery.html) 17:09:18 INFO - PROCESS | 1645 | RSS: Main: 204169216 17:09:18 INFO - PROCESS | 1645 | 17:12:20 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49248/tests/dromaeo/cssquery-mootools.html) 17:12:21 INFO - PROCESS | 1645 | RSS: Main: 206680064 17:12:21 INFO - PROCESS | 1645 | 17:15:22 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49248/tests/dromaeo/cssquery-prototype.html) 17:15:23 INFO - PROCESS | 1645 | RSS: Main: 209264640 17:15:23 INFO - PROCESS | 1645 | 17:18:26 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49248/tests/dromaeo/cssquery-yui.html) 17:18:26 INFO - PROCESS | 1645 | RSS: Main: 210436096 17:18:26 INFO - PROCESS | 1645 | 17:21:29 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-yui.html (next: http://localhost:49248/tests/dromaeo/cssquery-dojo.html) 17:21:29 INFO - PROCESS | 1645 | RSS: Main: 211091456 17:21:29 INFO - PROCESS | 1645 | 17:21:29 INFO - PROCESS | 1645 | __start_tp_report 17:21:29 INFO - PROCESS | 1645 | _x_x_mozilla_page_load 17:21:29 INFO - PROCESS | 1645 | _x_x_mozilla_page_load_details 17:21:29 INFO - PROCESS | 1645 | |i|pagename|runs| 17:21:29 INFO - PROCESS | 1645 | |0;dojo.html;3562.5190599269094;3571.71426285897;3578.0875876651457;3597.8201089945496;3603.927921441571;4903.926441103377;6054.576179667414;6064.605800622962;6084.634921904678;6156.384361563847;6125.63246205228;6128;6162.106494558287;6170.352113028135;6218.160548325975;5433.8369848904495;5483.643563168382;5500.202470641754;5504.669719816804;5564.1931919871595;5530.6128571000045;5533.391326954032;5537.861553461155;5543.750531226085;5582.7766889324375;4126.442930204429;5130.410002849682;5389.056915039852;5517.337919449652;5554.416786237448;5369.24830523725;5419.430959749228;5462.808801691922;5505.944940550623;5587.636803607753;5323.787048518055;5446;5447.346318441812;5467;5509.173623956399;5926.822195334147;6075.27096748393;6123.142760013601;6157.661328626924;6172.197614310156;6227.501799856001;6253.437190652863;6273.6235825850135;6291.528135389818;6393.2328120625825;6493.285738568754;6501.2198536175965;6501.252355979075;6515.087887695727;6522.315156908504;6164.137020817089;6223.657698826608;6229.034499652561;6237.438630523275;6249.312575616678;6304.211973503312;6331.620102793847;6432.453241474527;6454.128692626529;6463.515236357198;6330.4619107375465;6334.239891213084;6335.018072198816;6360.045993101072;6376.39424254695;6443.226812782496;6506.414422701885;6512.869742605216;6524.217093948661;6553.148090748172;6206.038064100072;6219.440250377488;6245.812625621239;6251.687415629201;6417.422431981144;6385.712642931079;6393.840153996187;6394.680265986682;6410.583312084699;6434.774782882577;5195.350581177353;5198.792048318025;5199.766010529535;5209.088409528393;5242.082635538765;4514.413126293561;4554.931676024862;4571.177188106173;4617.468991066004;4686.133065382915;6123.203983482119;6160.845978850564;6172.351903050115;6191.473724733358;6197;6452.967735161388;6462.3537646235;6472.967635161794;6478.514111441661;6483.351664833479;6049.153118563405;6110.419510146529;6121.4490695837585;6124.11200375943;6134.447899689049;4739.526047395302;5510.366307874566;5696.145578163226;5709.914351284734;5751.079827227623;6636.203655561363;6654.101696271038;6683.097781799394;6688.3646053624825;6704.698288577123;6215.378462153749;6232.657203853832;6321.52588555851;6327.019312006646;6401.29585745577;6640.767573134917;6660.433863121689;6666.899996500153;6671.199456065303;6689.130413046468;5282.947170528246;5478.506934375925;6234.220722409699;6411.134496842867;6411.775587854403;5287.418383977682;5935.486450954979;6381.106645069601;6443.677816109082;6490.383413575702;5182.740862956762;5360.946390536201;6168;6265.718042687999;6311.589746666452;5191.844244672666;6263.154474145932;6294.527910406646;6311.747530098744;6334.619922804646;6063.363346848518;6119.68535298207;6134.773364742421;6181.690915454299;6246.968765156145;5068.721220332917;5214.661047031931;6189.412005859436;6205;6312.936870631235;6176.7838125665385;6198.039303907902;6208.2860471044605;6215.96892015537;6229.470495007975 17:21:29 INFO - PROCESS | 1645 | |1;ext.html;485.47083678790125;485.5921026337873;494.8589651949195;510.2499325990792;534.3561008984175;39799.602003979955;43112;43254.35118473225;43444.565554344525;43585.78207108964;44106.77946610263;44618;44638.553614463846;44791.77604111983;44834.1033179337;9567.473788941605;9775.755606109833;9794.559244834;11579.594714185005;11993.040556755439;11752.236104653213;11793;11836.40817959103;11854.940725296361;11878.881211187863;11707.824382634268;11813.940930295337;11836.112291578165;11863.110266729987;11875.821862672025;11677.474513646906;11767.235129716584;11825.231359961575;11830.171887967846;11857.703557411047;11781.762914893985;11803.645890623295;11854.525818967317;11860.466279017379;11867.881321186764;39334;39706.20587588261;39720.404193937116;39723;39775.20449590992;3999.4800675911947;4029.49631296088;4041.333180025292;4109.301418758818;4111.444954931076;28662.713372866212;28730.281742956384;28874.422511549652;29040;31348.059558213292;3264.9183770405693;3456.0668619472603;3461.0309113448275;3498.073010652179;3506.982465087658;500595;503905;506668;507537;509309.45345273404;59992.70003649954;60231.397686023454;60232.795344092876;60248.79502409927;60275.69862150661;56060.439395606336;59973.40026599678;61069.08396374058;61216;61460;16176.27206775698;16491.09298988543;16591;16638.75041874373;16686.082265475514;12227.266364018187;12509.812352814715;14027.929860350632;14065.929670351583;14193.645158871112;21995.890020549796;22936.655950160428;22996.65505017426;23000.539989200122;23012.539749204923;7678.654460549177;8187.976502937133;8878.422902511316;9099.317551183687;9183.908160918305;894.9507777072323;900.9909900901038;903;915.4827522449795;932.9626814927462;25364.74635253624;27598;30964.845175774426;32129.51805722916;32170.19574510656;1083.712816103733;1091.8908109189017;1105.8949399807007;1142.7714457108611;1227.8281040654315;15142.79036693355;16435.09606971604;16518.75221871673;16629.251683674265;16705.33178672864;665460;665977;669768;672867;674249;65289.67355163194;65903;66466;66560;66572.33427665758;1446.768517037269;1482.2959094430275;1498.243387089513;1502.2038319690582;1509.6527798606382;30223;30316.24209394783;31423.214419638778;31504.842475787475;32052;48363.516364837305;49641;49713.25430118479;51532.484675152766;54817.1777423331;520263;532532.0240392794;562728;567279;570088;35978.28043439169;36691.266174675824;41275.793621031706;42253.78873105615;42338.15323693571;38100;44957.325640116076;45144.77427612841;47063.05873882572;47378;31877.840610797724;31894.36211275715;32002.67997319997;32470.512942306355;32597;31517;31689.5246571297;31890.68109318877;32027.199320017186;32350.514742279356;36671.63328366789;37274.25451491009;37390.8130459346;37565.248695026494;38373.61626383701;31684;31943.36113277675;32161.839190803898;32935.67064329326;33195;24067.638985414884;24829.751702483467;25291.367715806515;25524.744752552237;26016.219513414628 17:21:29 INFO - PROCESS | 1645 | |2;jquery.html;401.1355528835359;406.29913399386044;411.4342778679316;415.98128084236214;435.7429116821077;20827;21753.456163595918;21970.45073873154;22006.00972956219;22396;22040;22294;22981.310560683163;23041.884790576023;23433.414164645892;5923.815236952605;6082.4221698938645;6222.377762223764;6304.085907543404;6316.494680425555;5927.377625349341;6229.0033594624865;6267.404596563342;6272;6277.68611569422;5988.580799344048;6121.204243448346;6198.411150940676;6225.2840923293725;6230.065490176465;6043.788467403664;6118.847028824271;6129.2032035835055;6157.507399408059;6286.245650521921;4907.263910413467;5436.565074794007;5444;5446.8910621787345;5472.2338872557875;26871.46257074867;27389.863050684817;27598.724012759816;27991.86004069987;28075.57886631702;2788.219298596396;2850.1164638961986;2898.130560831742;2899.217211352944;2906.7529260012925;14029.947753918497;16348.264328105144;16423.42518011864;16475.58811029722;17037.82962170375;1881.8024107468655;1936.3997160880172;1942.1357495914324;1943.494691380237;1949.2495389275089;129041.35479322543;130192;130684;134719.32640336832;143475.56524434726;11091.894269604361;11603.36181510025;11869.821952670716;12243.142979991315;12306.384680765927;16487;16535.338586456648;16589.751153732705;16596;16629.584260393585;9030.819383612292;9174.266058715286;9250.259979201648;9321.813563728687;9330;8924.821503569892;8962.462252264884;9009.954950225207;9160.6793762218;9176.311776616647;4117.279476091672;4209.915801683949;4859.198232291703;5007.674501157413;5102.183650615883;1644.4644015367057;2056.845736569763;2093.6964140199584;2137.7648458669532;2141.3575927221773;1009.4397609326894;1020.5866624017285;1023.7542989682422;1026.8921763214905;1033.8811036730722;21374.893125534272;22201.333959981228;22295.773732444548;22648.207312743976;22730;1012.4583347908833;1071.3304184884448;1084.9096657858959;1104.5195340027115;1118.4743170709753;10485.213608979357;11685.824712629317;11800.58697945585;11808.645740627795;11828.11289153317;399347;399452;401819;401934;768416;117860;122971;161142;161664;163724.3627563733;1043.3531210649446;1087.5704096881686;1102.167863263231;1113.3709454158375;1122.4499995002552;19226.7115993263;19588.706169407757;19858.50353741168;19899.801001989796;19961.800381995996;55500;56759.71620141873;56971;57156.14265785933;57336.71331643315;80744;83338;83503;89871.55064224637;92936;8520.403571750167;8574.828503429771;8608.311335093053;8981.326400519856;8986.985053224766;9416.246700263826;12299.508019679113;13099.869001309866;13382;13382.93308533451;8890.511021893599;11079.224454288122;11243.269187502787;11275.548978040786;11487;10171.338862973884;10478;10607.94696026515;10645.946770266099;10862.891371086187;9747.561359738687;11482.081433485468;11487.25332853362;11599.594014209462;11887.524499020288;7565.735199268;8348.248657620843;8552.273056790242;8787.91212087871;8789.604467799092;7811.96094019549;11022.897710228914;11048.226624136234;11138.55445782192;11177.776444471228 17:21:29 INFO - PROCESS | 1645 | |3;mootools.html;1298.6363818130924;1441.9632047360442;1491.4928924165788;1494.6711723420851;1498.220925118938;7716.38268938485;7958.442908996372;8230.012398512172;8703.52130632815;8705.173008564185;8191.303739182176;8505.574721263944;8508.95745521273;8613;8613.91386086139;2361.102780943239;2405.374602603327;2409.5301416223842;2414.6860908081926;2424.6847909771705;2362.4684445999596;2386.2721869829693;2397.5324811661735;2406.3262286559793;2409.277216834951;2370.9051637934463;2373.703287089114;2400.291913885408;2403.807695384374;2408.2895545814026;2354.164271683545;2362.6692263083182;2374.465745207332;2387.415083304593;2390.486045500226;2384.7972922301624;2385.7733515316017;2401.7718316760056;2414.9034038638433;2420.515896820625;8482.363822713321;8630.697925572575;8644.61099250529;8684.2184203422;8691;5200;5262.657927234717;5331.360236771573;5350.571954243651;5356.089464790956;3470.791752494833;3472.965270347315;3490.720742340632;3597.388443964532;3656.3784156693428;1264.4120483974996;1277.310252463678;1307.9084464087518;1315.1451556488264;1321.2138777427415;11051.005409513182;11074.058705010084;11136.164787640879;11137.610183643614;11164.330140191534;4672.766361681903;4759.738214398241;4795.664303498791;4843.612510999112;4875.366202393666;9672.08115229052;9733.610655573839;9743.756406089904;9745.12293893553;9755.853662195073;5393.2449457076045;5394.8381548552825;5396.271503347077;5404.351477822608;5405.4324295949145;4415.823367065345;4498.190325741332;4833.130036593377;4875.488073752276;4940.184869496565;3965.4448377227213;4040.5151381834366;4075;4106.1582375612925;4109.671226301889;1279.9104062715521;1286.7619490394306;1293.741251749635;1298.9997701769773;1303.89568834493;923.2798417234568;928.6656803550717;928.800307933784;935.4527601353285;960.4141473701177;5665.915011274834;5729.942700573024;5894.616849904743;6066.029435290332;6083.087536869417;568.2186992884783;572.8854229154185;582.5921854701726;587.7149582452593;591.1251348004977;3821.178446633987;3822.7515211511163;3856;3929.410588411761;3938.448617193595;9782.853257201148;10293.43386113771;10299.9485002576;10309.74225644365;10358.689239322832;10238.53926573306;10270.024347686958;10281.280310378203;10291.742706432398;10295.227857910537;7822.530648161129;8114.350851931831;8134.430589858653;8169.5098294102545;8177.141400152898;4730.455997560324;5524.06090964545;5575.581831362745;5643.435656434323;5745.712714364348;8126;9190.172884440433;9414.811703765748;9481.146696797321;9493.430394176372;9272.869507157731;9400.247980161434;9426;9535.666251681158;9699.321047526888;7171.390431813145;7343.963280183565;7377.631118443949;7452.06849143857;7460.850782984201;7047.7666408379355;8252.257296843312;8263.462874913352;8272.669093236444;8287.83424331498;6901.275366086488;7018.192907815666;7154.678039488132;7237.167725711611;7265.963670181826;6862.988036356694;7002.8949565757575;7013.649317534205;7092.929070709227;7207.207207207098;6455.483561315177;6506.739730410731;6513.934860651333;6597.571157874723;6603.372679595431;5045.167547354646;5057.418396884258;5088.516590923857;5105.183170692671;5124.256982737407;5055.140626093499;5098.923516147335;5136.2552429896705;5162.9483705162465;5217.686938783685 17:21:29 INFO - PROCESS | 1645 | |4;prototype.html;293.89419808868803;356.80910712768673;367.1358947081962;384.53855373551744;387.8661861657727;5502.202180683808;5566.972165139168;5677.914831277534;5682.522544138528;5784.496031031925;4226.738694798283;4674.719516828993;5557.666540007603;5594.440555944414;5625.5499560035205;3306.8346582670893;4150.0119913662265;4358.934615980755;4406.5593440656;4467.955320446787;3680.392735198689;4150.252954468195;4345.369921361401;4442.400275962736;4453.574856046067;4168.958310416902;4300.483941926973;4359.7602131882895;4370.32259999699;4388.780560971954;4364.301711726124;4383.802728877208;4393.450818647669;4415.470143582791;4432.911341773147;3886.8250928708276;3931.722190288168;3936.488256526633;3942.7831469269177;3949.328614135604;4729.456112547033;4823.300621409911;5369.033573956686;5394.757235924393;5469.917951230735;5029.19532874742;5063.670861393997;5091.694498330112;5097.23541468779;5105.923411148836;5043.67216130954;5471.616986810925;5975.312839023524;5986.4612184903135;6048.334683184846;3326.750493712981;3903.3754599264266;4069.6337329640473;4074.225897079575;4097.405876147942;5143.562797162245;5821.941780582182;6005.549583781234;6255.906161407583;6260.5304602154565;3820.815547180383;3978.244133614604;4087.141700242923;4099.262132816062;4122.793860306972;3626.7642603230706;4150.481189851269;4164.708470407103;4180.540140584533;4184.4141820145205;4087.6525495332635;4517.322401639781;4898.461169271377;4904.950950490521;4913.469563174967;5534.861628459321;5657.670447444847;5997.850053748692;6026.397360263939;6032.909506357408;1156.248438514972;1225.221984040123;1365.9248741319125;1366.6924941887996;1366.924819134957;836.2640876029177;859.7361878039272;907.5280853955906;915.4873270968279;919.8160367926307;4486.330633273197;4758.3100450434495;4972.627052970969;5119.846404607868;5276.868078297996;5273.525382715574;5835.328937172279;5863.508009096117;5882.794102206402;5905.645661260338;4629.814807407666;4949.802007919715;5243.423223445494;5307.389650190277;5330.680159190461;2662.8535430551506;3063.5711000459955;3072.032309822399;3077.19983809474;3082.830444325584;5262.868483276114;5504.311961004874;5522.8678120985105;5952.7023648817385;6104.603200791934;5659.377468478464;5663.830085097454;6293.748250069946;6315.810525684236;6322.743786949724;5120.795168193231;5752.1526844212385;6184.938150618526;6316.05259211122;6354.872902541923;5731.914021289601;5925.259342582177;6073.696315184311;6184.443400094013;6207.782727604512;5548.722563871871;6030.7286172121485;6063.242094738158;6070.1501789750355;6097.817065488042;5270.841874743764;6179.620845974422;6210.975189093749;6357.427831495188;6383.585066970632;3822.2355528893777;3888.6889048875455;3991.501062367204;4005.2189822984237;4031.254217969685;5149.794008239628;5322.498425739896;6109.7861574844665;6125.938740612537;6128.233970753656;4914.778834952364;5541.972290138685;5828;5901.822945311647;5903.498202652822;5011.569388846479;5347.919781203207;5728.742206600797;5910.881782364415;5911.231539899785;5078.08594453002;5540.252065971043;5703.7433315500075;5786.42135786418;5834.883302333845;5409.972950135224;5546.029444847055;5660.405657406078;5906.021482803309;5925.348211696623;5081.898362032664;5318.867028324168;5789.2763404574425;5803.65178089316;5833.387494313036 17:21:29 INFO - PROCESS | 1645 | |5;yui.html;733.1605311917857;741.417986880299;783.5612057247941;872.6334939325483;873.8732883731857;15639.452619158332;16343.754843677325;16379.59051023725;16386.59033524161;16437.09595972221;15062.39750409985;15172.089674619503;15179.620509487268;15198.164100974442;15232.69534609307;700.3696672994321;712.6899798587605;865.5412631305403;871.8735783311416;872.7774417523523;820.5199958024563;834.2783492279165;847.4533925617973;861.9051904290523;864.8702694595798;910.0353625157321;935.8596210568435;936.1761649748212;939.037486576258;939.6194541210821;899.8560230363111;911.8677791720197;925.9583318750672;929.6606738540452;933.9719808405758;670.8222321084917;689.1179290508157;697.0380874393305;698.2912343970822;699.1121275979477;14889.70220595593;15454.922725386301;15751.684966300725;16260.674786504203;16503.09232992185;14033.85966140346;14221.21783301918;14315.498957536436;14354;14512.92743536286;11755.588554400554;11897.28616283017;11924;12018.218815776947;12098.395080245953;3776.206996530732;3893.980530097331;3900.2589507993675;3913.4716813230134;3935.74417662851;86952;110019.29093326123;111902.88097119005;112069;113901;11158.721031974266;11272.943635281936;11458.59894903691;11799;12295.385230738428;19931.501712457306;24186.637200442008;24517.63223551648;24551;24583.754162458146;8200.671973121009;8208.753737387888;8242.29940455068;8352.0812710603;8362.121977192308;4366.170427618742;4407.977960110179;4510.548945105464;4531.682782205214;4691.225947718656;3771.283456143324;3816.2558301131567;3829.2532956073856;3865.091703449687;3869.5356557212754;694.9131358580178;697.8953157026486;698.0785363320368;705.1467724053849;712.9251428599922;680.4454369688688;704.3132945378215;711.3882061427169;714.9034880291094;750.3434494817035;8772.736817895473;10488.4231367274;10682.768230376176;10878.564857405616;10937.343759374464;645.6965226343611;697.7057558229512;704.728679458402;711.6192836832373;724.822418507469;4743.193657078236;5133.537981581676;5142.588592912558;5147.588192944556;5167.689938603696;94033;95449.52275238579;107414;107734;112894;83050.16949830545;93135;96051;96850.51574742081;97823;78574.607126964;82372.9999999988;82541;83384;83685.58157209175;9789.162640258744;9792.461414622265;9795.412275263507;9844.803103938024;9871.160951319218;46239.76880115578;48146.62973591815;50222;50937.72655683637;52621.47378526166;55365;66498.33501665115;72213.27786722066;73008.634956827;74724.25275747172;11010;12139.02887768994;13654.658633534242;13844.238566878917;14140.080894742221;14270.71458570802;15114.622134446287;15319.234038298264;15901.443449479213;16111.436099736453;13784.931075344559;13821.239831809347;14100.858991409954;14272.785908211592;14281.785773213618;13642.522511712043;13695.794563081761;14206.063249295132;14414;14646.853531464549;10800.626382640316;13362.732745344843;13727.313634318443;14343.856561434251;14536.746804245358;8413.873791893004;10288.176945844465;10355.223358248011;10552.366857988545;10753.623623173151;9141.77145571366;9184.770380740534;10588.841167382341;10852.620158294421;11476.942615286871 17:21:29 INFO - PROCESS | 1645 | __end_tp_report 17:21:29 INFO - PROCESS | 1645 | __start_cc_report 17:21:29 INFO - PROCESS | 1645 | _x_x_mozilla_cycle_collect,376 17:21:29 INFO - PROCESS | 1645 | __end_cc_report 17:21:29 INFO - PROCESS | 1645 | __startTimestamp1491178889666__endTimestamp 17:21:29 INFO - PROCESS | 1645 | 17:21:29 INFO - PROCESS | 1645 | ------- Summary: start ------- 17:21:29 INFO - PROCESS | 1645 | Number of tests: 6 17:21:29 INFO - PROCESS | 1645 | 17:21:29 INFO - PROCESS | 1645 | [#0] dojo.html Cycles:165 Average:5925.68 Median:6181.69 stddev:672.01 (10.9%) stddev-sans-first:647.99 17:21:29 INFO - PROCESS | 1645 | Values: 3562.5 3571.7 3578.1 3597.8 3603.9 4903.9 6054.6 6064.6 6084.6 6156.4 6125.6 6128.0 6162.1 6170.4 6218.2 5433.8 5483.6 5500.2 5504.7 5564.2 5530.6 5533.4 5537.9 5543.8 5582.8 4126.4 5130.4 5389.1 5517.3 5554.4 5369.2 5419.4 5462.8 5505.9 5587.6 5323.8 5446.0 5447.3 5467.0 5509.2 5926.8 6075.3 6123.1 6157.7 6172.2 6227.5 6253.4 6273.6 6291.5 6393.2 6493.3 6501.2 6501.3 6515.1 6522.3 6164.1 6223.7 6229.0 6237.4 6249.3 6304.2 6331.6 6432.5 6454.1 6463.5 6330.5 6334.2 6335.0 6360.0 6376.4 6443.2 6506.4 6512.9 6524.2 6553.1 6206.0 6219.4 6245.8 6251.7 6417.4 6385.7 6393.8 6394.7 6410.6 6434.8 5195.4 5198.8 5199.8 5209.1 5242.1 4514.4 4554.9 4571.2 4617.5 4686.1 6123.2 6160.8 6172.4 6191.5 6197.0 6453.0 6462.4 6473.0 6478.5 6483.4 6049.2 6110.4 6121.4 6124.1 6134.4 4739.5 5510.4 5696.1 5709.9 5751.1 6636.2 6654.1 6683.1 6688.4 6704.7 6215.4 6232.7 6321.5 6327.0 6401.3 6640.8 6660.4 6666.9 6671.2 6689.1 5282.9 5478.5 6234.2 6411.1 6411.8 5287.4 5935.5 6381.1 6443.7 6490.4 5182.7 5360.9 6168.0 6265.7 6311.6 5191.8 6263.2 6294.5 6311.7 6334.6 6063.4 6119.7 6134.8 6181.7 6247.0 5068.7 5214.7 6189.4 6205.0 6312.9 6176.8 6198.0 6208.3 6216.0 6229.5 17:21:29 INFO - PROCESS | 1645 | 17:21:29 INFO - PROCESS | 1645 | [#1] ext.html Cycles:180 Average:71393.33 Median:3502.53 stddev:154736.77 (4417.9%) stddev-sans-first:155079.26 17:21:29 INFO - PROCESS | 1645 | Values: 485.5 485.6 494.9 510.2 534.4 39799.6 43112.0 43254.4 43444.6 43585.8 44106.8 44618.0 44638.6 44791.8 44834.1 9567.5 9775.8 9794.6 11579.6 11993.0 11752.2 11793.0 11836.4 11854.9 11878.9 11707.8 11813.9 11836.1 11863.1 11875.8 11677.5 11767.2 11825.2 11830.2 11857.7 11781.8 11803.6 11854.5 11860.5 11867.9 39334.0 39706.2 39720.4 39723.0 39775.2 3999.5 4029.5 4041.3 4109.3 4111.4 28662.7 28730.3 28874.4 29040.0 31348.1 3264.9 3456.1 3461.0 3498.1 3507.0 500595.0 503905.0 506668.0 507537.0 509309.5 59992.7 60231.4 60232.8 60248.8 60275.7 56060.4 59973.4 61069.1 61216.0 61460.0 16176.3 16491.1 16591.0 16638.8 16686.1 12227.3 12509.8 14027.9 14065.9 14193.6 21995.9 22936.7 22996.7 23000.5 23012.5 7678.7 8188.0 8878.4 9099.3 9183.9 895.0 901.0 903.0 915.5 933.0 25364.7 27598.0 30964.8 32129.5 32170.2 1083.7 1091.9 1105.9 1142.8 1227.8 15142.8 16435.1 16518.8 16629.3 16705.3 665460.0 665977.0 669768.0 672867.0 674249.0 65289.7 65903.0 66466.0 66560.0 66572.3 1446.8 1482.3 1498.2 1502.2 1509.7 30223.0 30316.2 31423.2 31504.8 32052.0 48363.5 49641.0 49713.3 51532.5 54817.2 520263.0 532532.0 562728.0 567279.0 570088.0 35978.3 36691.3 41275.8 42253.8 42338.2 38100.0 44957.3 45144.8 47063.1 47378.0 31877.8 31894.4 32002.7 32470.5 32597.0 31517.0 31689.5 31890.7 32027.2 32350.5 36671.6 37274.3 37390.8 37565.2 38373.6 31684.0 31943.4 32161.8 32935.7 33195.0 24067.6 24829.8 25291.4 25524.7 26016.2 17:21:29 INFO - PROCESS | 1645 | 17:21:29 INFO - PROCESS | 1645 | [#2] jquery.html Cycles:180 Average:33389.52 Median:22522.10 stddev:85398.65 (379.2%) stddev-sans-first:85602.30 17:21:29 INFO - PROCESS | 1645 | Values: 401.1 406.3 411.4 416.0 435.7 20827.0 21753.5 21970.5 22006.0 22396.0 22040.0 22294.0 22981.3 23041.9 23433.4 5923.8 6082.4 6222.4 6304.1 6316.5 5927.4 6229.0 6267.4 6272.0 6277.7 5988.6 6121.2 6198.4 6225.3 6230.1 6043.8 6118.8 6129.2 6157.5 6286.2 4907.3 5436.6 5444.0 5446.9 5472.2 26871.5 27389.9 27598.7 27991.9 28075.6 2788.2 2850.1 2898.1 2899.2 2906.8 14029.9 16348.3 16423.4 16475.6 17037.8 1881.8 1936.4 1942.1 1943.5 1949.2 129041.4 130192.0 130684.0 134719.3 143475.6 11091.9 11603.4 11869.8 12243.1 12306.4 16487.0 16535.3 16589.8 16596.0 16629.6 9030.8 9174.3 9250.3 9321.8 9330.0 8924.8 8962.5 9010.0 9160.7 9176.3 4117.3 4209.9 4859.2 5007.7 5102.2 1644.5 2056.8 2093.7 2137.8 2141.4 1009.4 1020.6 1023.8 1026.9 1033.9 21374.9 22201.3 22295.8 22648.2 22730.0 1012.5 1071.3 1084.9 1104.5 1118.5 10485.2 11685.8 11800.6 11808.6 11828.1 399347.0 399452.0 401819.0 401934.0 768416.0 117860.0 122971.0 161142.0 161664.0 163724.4 1043.4 1087.6 1102.2 1113.4 1122.4 19226.7 19588.7 19858.5 19899.8 19961.8 55500.0 56759.7 56971.0 57156.1 57336.7 80744.0 83338.0 83503.0 89871.6 92936.0 8520.4 8574.8 8608.3 8981.3 8987.0 9416.2 12299.5 13099.9 13382.0 13382.9 8890.5 11079.2 11243.3 11275.5 11487.0 10171.3 10478.0 10607.9 10645.9 10862.9 9747.6 11482.1 11487.3 11599.6 11887.5 7565.7 8348.2 8552.3 8787.9 8789.6 7812.0 11022.9 11048.2 11138.6 11177.8 17:21:29 INFO - PROCESS | 1645 | 17:21:29 INFO - PROCESS | 1645 | [#3] mootools.html Cycles:180 Average:5561.16 Median:5240.17 stddev:3029.27 (57.8%) stddev-sans-first:3020.83 17:21:29 INFO - PROCESS | 1645 | Values: 1298.6 1442.0 1491.5 1494.7 1498.2 7716.4 7958.4 8230.0 8703.5 8705.2 8191.3 8505.6 8509.0 8613.0 8613.9 2361.1 2405.4 2409.5 2414.7 2424.7 2362.5 2386.3 2397.5 2406.3 2409.3 2370.9 2373.7 2400.3 2403.8 2408.3 2354.2 2362.7 2374.5 2387.4 2390.5 2384.8 2385.8 2401.8 2414.9 2420.5 8482.4 8630.7 8644.6 8684.2 8691.0 5200.0 5262.7 5331.4 5350.6 5356.1 3470.8 3473.0 3490.7 3597.4 3656.4 1264.4 1277.3 1307.9 1315.1 1321.2 11051.0 11074.1 11136.2 11137.6 11164.3 4672.8 4759.7 4795.7 4843.6 4875.4 9672.1 9733.6 9743.8 9745.1 9755.9 5393.2 5394.8 5396.3 5404.4 5405.4 4415.8 4498.2 4833.1 4875.5 4940.2 3965.4 4040.5 4075.0 4106.2 4109.7 1279.9 1286.8 1293.7 1299.0 1303.9 923.3 928.7 928.8 935.5 960.4 5665.9 5729.9 5894.6 6066.0 6083.1 568.2 572.9 582.6 587.7 591.1 3821.2 3822.8 3856.0 3929.4 3938.4 9782.9 10293.4 10299.9 10309.7 10358.7 10238.5 10270.0 10281.3 10291.7 10295.2 7822.5 8114.4 8134.4 8169.5 8177.1 4730.5 5524.1 5575.6 5643.4 5745.7 8126.0 9190.2 9414.8 9481.1 9493.4 9272.9 9400.2 9426.0 9535.7 9699.3 7171.4 7344.0 7377.6 7452.1 7460.9 7047.8 8252.3 8263.5 8272.7 8287.8 6901.3 7018.2 7154.7 7237.2 7266.0 6863.0 7002.9 7013.6 7092.9 7207.2 6455.5 6506.7 6513.9 6597.6 6603.4 5045.2 5057.4 5088.5 5105.2 5124.3 5055.1 5098.9 5136.3 5162.9 5217.7 17:21:29 INFO - PROCESS | 1645 | 17:21:29 INFO - PROCESS | 1645 | [#4] prototype.html Cycles:180 Average:4747.18 Median:5272.18 stddev:1452.63 (27.6%) stddev-sans-first:1417.72 17:21:29 INFO - PROCESS | 1645 | Values: 293.9 356.8 367.1 384.5 387.9 5502.2 5567.0 5677.9 5682.5 5784.5 4226.7 4674.7 5557.7 5594.4 5625.5 3306.8 4150.0 4358.9 4406.6 4468.0 3680.4 4150.3 4345.4 4442.4 4453.6 4169.0 4300.5 4359.8 4370.3 4388.8 4364.3 4383.8 4393.5 4415.5 4432.9 3886.8 3931.7 3936.5 3942.8 3949.3 4729.5 4823.3 5369.0 5394.8 5469.9 5029.2 5063.7 5091.7 5097.2 5105.9 5043.7 5471.6 5975.3 5986.5 6048.3 3326.8 3903.4 4069.6 4074.2 4097.4 5143.6 5821.9 6005.5 6255.9 6260.5 3820.8 3978.2 4087.1 4099.3 4122.8 3626.8 4150.5 4164.7 4180.5 4184.4 4087.7 4517.3 4898.5 4905.0 4913.5 5534.9 5657.7 5997.9 6026.4 6032.9 1156.2 1225.2 1365.9 1366.7 1366.9 836.3 859.7 907.5 915.5 919.8 4486.3 4758.3 4972.6 5119.8 5276.9 5273.5 5835.3 5863.5 5882.8 5905.6 4629.8 4949.8 5243.4 5307.4 5330.7 2662.9 3063.6 3072.0 3077.2 3082.8 5262.9 5504.3 5522.9 5952.7 6104.6 5659.4 5663.8 6293.7 6315.8 6322.7 5120.8 5752.2 6184.9 6316.1 6354.9 5731.9 5925.3 6073.7 6184.4 6207.8 5548.7 6030.7 6063.2 6070.2 6097.8 5270.8 6179.6 6211.0 6357.4 6383.6 3822.2 3888.7 3991.5 4005.2 4031.3 5149.8 5322.5 6109.8 6125.9 6128.2 4914.8 5542.0 5828.0 5901.8 5903.5 5011.6 5347.9 5728.7 5910.9 5911.2 5078.1 5540.3 5703.7 5786.4 5834.9 5410.0 5546.0 5660.4 5906.0 5925.3 5081.9 5318.9 5789.3 5803.7 5833.4 17:21:29 INFO - PROCESS | 1645 | 17:21:29 INFO - PROCESS | 1645 | [#5] yui.html Cycles:180 Average:20986.13 Median:4521.12 stddev:30116.64 (666.1%) stddev-sans-first:30162.73 17:21:29 INFO - PROCESS | 1645 | Values: 733.2 741.4 783.6 872.6 873.9 15639.5 16343.8 16379.6 16386.6 16437.1 15062.4 15172.1 15179.6 15198.2 15232.7 700.4 712.7 865.5 871.9 872.8 820.5 834.3 847.5 861.9 864.9 910.0 935.9 936.2 939.0 939.6 899.9 911.9 926.0 929.7 934.0 670.8 689.1 697.0 698.3 699.1 14889.7 15454.9 15751.7 16260.7 16503.1 14033.9 14221.2 14315.5 14354.0 14512.9 11755.6 11897.3 11924.0 12018.2 12098.4 3776.2 3894.0 3900.3 3913.5 3935.7 86952.0 110019.3 111902.9 112069.0 113901.0 11158.7 11272.9 11458.6 11799.0 12295.4 19931.5 24186.6 24517.6 24551.0 24583.8 8200.7 8208.8 8242.3 8352.1 8362.1 4366.2 4408.0 4510.5 4531.7 4691.2 3771.3 3816.3 3829.3 3865.1 3869.5 694.9 697.9 698.1 705.1 712.9 680.4 704.3 711.4 714.9 750.3 8772.7 10488.4 10682.8 10878.6 10937.3 645.7 697.7 704.7 711.6 724.8 4743.2 5133.5 5142.6 5147.6 5167.7 94033.0 95449.5 107414.0 107734.0 112894.0 83050.2 93135.0 96051.0 96850.5 97823.0 78574.6 82373.0 82541.0 83384.0 83685.6 9789.2 9792.5 9795.4 9844.8 9871.2 46239.8 48146.6 50222.0 50937.7 52621.5 55365.0 66498.3 72213.3 73008.6 74724.3 11010.0 12139.0 13654.7 13844.2 14140.1 14270.7 15114.6 15319.2 15901.4 16111.4 13784.9 13821.2 14100.9 14272.8 14281.8 13642.5 13695.8 14206.1 14414.0 14646.9 10800.6 13362.7 13727.3 14343.9 14536.7 8413.9 10288.2 10355.2 10552.4 10753.6 9141.8 9184.8 10588.8 10852.6 11476.9 17:21:29 INFO - PROCESS | 1645 | -------- Summary: end -------- 17:21:29 INFO - PROCESS | 1645 | 17:21:30 INFO - TEST-INFO | 1645: exit 0 17:21:30 INFO - TEST-OK | dromaeo_css | took 1114575ms 17:21:30 INFO - TEST-START | kraken 17:21:30 INFO - operating with platform_type : mac_ 17:21:30 INFO - Initialising browser for kraken test... 17:21:30 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/tmpKh5xDZ/profile http://localhost:49248/getInfo.html) 17:21:32 INFO - PROCESS | 1657 | 2017-04-02 17:21:32.973 plugin-container[1659:10033] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 17:21:32 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:21:32 INFO - PROCESS | 1657 | 2017-04-02 17:21:32.973 plugin-container[1659:10033] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 17:21:32 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:21:32 INFO - PROCESS | 1657 | 2017-04-02 17:21:32.973 plugin-container[1659:10033] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:21:33 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 17:21:33 INFO - PROCESS | 1657 | colorDepth:24 17:21:33 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 17:21:33 INFO - PROCESS | 1657 | __metrics 17:21:33 ERROR - PROCESS | 1657 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 17:21:33 INFO - TEST-INFO | 1657: exit 0 17:21:33 INFO - Browser initialized. 17:21:33 INFO - Running cycle 1/1 for kraken test... 17:21:33 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/tmpKh5xDZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:21:36 INFO - PROCESS | 1660 | 2017-04-02 17:21:36.076 plugin-container[1661:10155] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4247, name = 'com.apple.tsm.portname' 17:21:36 INFO - PROCESS | 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:21:36 INFO - PROCESS | 1660 | 2017-04-02 17:21:36.077 plugin-container[1661:10155] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 17:21:36 INFO - PROCESS | 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:21:36 INFO - PROCESS | 1660 | 2017-04-02 17:21:36.077 plugin-container[1661:10155] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:21:45 INFO - PROCESS | 1660 | RSS: Main: 173424640 17:21:45 INFO - PROCESS | 1660 | 17:21:48 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-beat-detection) 17:21:49 INFO - PROCESS | 1660 | RSS: Main: 186400768 17:21:49 INFO - PROCESS | 1660 | 17:21:52 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-dft) 17:21:52 INFO - PROCESS | 1660 | RSS: Main: 194199552 17:21:52 INFO - PROCESS | 1660 | 17:21:56 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-fft) 17:21:56 INFO - PROCESS | 1660 | RSS: Main: 197890048 17:21:56 INFO - PROCESS | 1660 | 17:21:58 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-oscillator) 17:21:59 INFO - PROCESS | 1660 | RSS: Main: 199565312 17:21:59 INFO - PROCESS | 1660 | 17:22:01 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:22:01 INFO - PROCESS | 1660 | RSS: Main: 199639040 17:22:01 INFO - PROCESS | 1660 | 17:22:04 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-darkroom) 17:22:05 INFO - PROCESS | 1660 | RSS: Main: 203554816 17:22:05 INFO - PROCESS | 1660 | 17:22:08 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-desaturate) 17:22:08 INFO - PROCESS | 1660 | RSS: Main: 203800576 17:22:08 INFO - PROCESS | 1660 | 17:22:12 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49248/tests/kraken/driver.html?testName=json-parse-financial) 17:22:12 INFO - PROCESS | 1660 | RSS: Main: 203276288 17:22:12 INFO - PROCESS | 1660 | 17:22:14 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49248/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:22:15 INFO - PROCESS | 1660 | RSS: Main: 202358784 17:22:15 INFO - PROCESS | 1660 | 17:22:17 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:22:17 INFO - PROCESS | 1660 | RSS: Main: 202346496 17:22:17 INFO - PROCESS | 1660 | 17:22:20 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:22:20 INFO - PROCESS | 1660 | RSS: Main: 202330112 17:22:20 INFO - PROCESS | 1660 | 17:22:23 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:22:24 INFO - PROCESS | 1660 | RSS: Main: 202735616 17:22:24 INFO - PROCESS | 1660 | 17:22:27 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:22:27 INFO - PROCESS | 1660 | RSS: Main: 202772480 17:22:27 INFO - PROCESS | 1660 | 17:22:29 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49248/tests/kraken/driver.html?testName=ai-astar) 17:22:29 INFO - PROCESS | 1660 | RSS: Main: 204677120 17:22:29 INFO - PROCESS | 1660 | 17:22:29 INFO - PROCESS | 1660 | __start_tp_report 17:22:29 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 17:22:29 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 17:22:29 INFO - PROCESS | 1660 | |i|pagename|runs| 17:22:29 INFO - PROCESS | 1660 | |0;ai-astar;108;118;136;172;117;117;119;117;117;119 17:22:29 INFO - PROCESS | 1660 | |1;audio-beat-detection;130;126;145;148;109;111;108;110;129;107 17:22:29 INFO - PROCESS | 1660 | |2;audio-dft;195;203;197;198;197;194;197;196;198;195 17:22:29 INFO - PROCESS | 1660 | |3;audio-fft;79;59;60;91;52;73;85;82;95;50 17:22:29 INFO - PROCESS | 1660 | |4;audio-oscillator;81;67;85;71;69;71;81;69;72;82 17:22:29 INFO - PROCESS | 1660 | |5;imaging-gaussian-blur;91;88;92;89;102;88;82;133;97;99 17:22:29 INFO - PROCESS | 1660 | |6;imaging-darkroom;111;102;100;102;110;171;103;100;101;101 17:22:29 INFO - PROCESS | 1660 | |7;imaging-desaturate;91;95;125;105;150;150;92;83;153;102 17:22:29 INFO - PROCESS | 1660 | |8;json-parse-financial;57;58;65;90;93;92;93;94;60;60 17:22:29 INFO - PROCESS | 1660 | |9;json-stringify-tinderbox;68;81;108;117;74;75;77;91;101;100 17:22:29 INFO - PROCESS | 1660 | |10;stanford-crypto-aes;89;103;104;84;87;80;84;74;106;104 17:22:29 INFO - PROCESS | 1660 | |11;stanford-crypto-ccm;116;146;147;151;146;155;150;158;194;143 17:22:29 INFO - PROCESS | 1660 | |12;stanford-crypto-pbkdf2;148;201;165;164;164;161;162;166;163;160 17:22:29 INFO - PROCESS | 1660 | |13;stanford-crypto-sha256-iterative;65;62;67;65;71;77;86;84;87;80 17:22:29 INFO - PROCESS | 1660 | __end_tp_report 17:22:29 INFO - PROCESS | 1660 | __start_cc_report 17:22:29 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,927 17:22:29 INFO - PROCESS | 1660 | __end_cc_report 17:22:29 INFO - PROCESS | 1660 | __startTimestamp1491178949989__endTimestamp 17:22:29 INFO - PROCESS | 1660 | 17:22:29 INFO - PROCESS | 1660 | ------- Summary: start ------- 17:22:29 INFO - PROCESS | 1660 | Number of tests: 14 17:22:29 INFO - PROCESS | 1660 | 17:22:30 INFO - PROCESS | 1660 | [#0] ai-astar Cycles:10 Average:124.00 Median:118.50 stddev:18.21 (15.4%) stddev-sans-first:18.38 17:22:30 INFO - PROCESS | 1660 | Values: 108.0 118.0 136.0 172.0 117.0 117.0 119.0 117.0 117.0 119.0 17:22:30 INFO - PROCESS | 1660 | 17:22:30 INFO - PROCESS | 1660 | [#1] audio-beat-detection Cycles:10 Average:122.30 Median:127.50 stddev:15.59 (12.2%) stddev-sans-first:16.29 17:22:30 INFO - PROCESS | 1660 | Values: 130.0 126.0 145.0 148.0 109.0 111.0 108.0 110.0 129.0 107.0 17:22:30 INFO - PROCESS | 1660 | 17:22:30 INFO - PROCESS | 1660 | [#2] audio-dft Cycles:10 Average:197.00 Median:197.00 stddev:2.49 (1.3%) stddev-sans-first:2.54 17:22:30 INFO - PROCESS | 1660 | Values: 195.0 203.0 197.0 198.0 197.0 194.0 197.0 196.0 198.0 195.0 17:22:30 INFO - PROCESS | 1660 | 17:22:30 INFO - PROCESS | 1660 | [#3] audio-fft Cycles:10 Average:72.60 Median:80.50 stddev:16.34 (20.3%) stddev-sans-first:17.16 17:22:30 INFO - PROCESS | 1660 | Values: 79.0 59.0 60.0 91.0 52.0 73.0 85.0 82.0 95.0 50.0 17:22:30 INFO - PROCESS | 1660 | 17:22:30 INFO - PROCESS | 1660 | [#4] audio-oscillator Cycles:10 Average:74.80 Median:76.50 stddev:6.65 (8.7%) stddev-sans-first:6.66 17:22:30 INFO - PROCESS | 1660 | Values: 81.0 67.0 85.0 71.0 69.0 71.0 81.0 69.0 72.0 82.0 17:22:30 INFO - PROCESS | 1660 | 17:22:30 INFO - PROCESS | 1660 | [#5] imaging-gaussian-blur Cycles:10 Average:96.10 Median:90.00 stddev:14.26 (15.8%) stddev-sans-first:15.00 17:22:30 INFO - PROCESS | 1660 | Values: 91.0 88.0 92.0 89.0 102.0 88.0 82.0 133.0 97.0 99.0 17:22:30 INFO - PROCESS | 1660 | 17:22:30 INFO - PROCESS | 1660 | [#6] imaging-darkroom Cycles:10 Average:110.10 Median:102.50 stddev:21.76 (21.2%) stddev-sans-first:23.08 17:22:30 INFO - PROCESS | 1660 | Values: 111.0 102.0 100.0 102.0 110.0 171.0 103.0 100.0 101.0 101.0 17:22:30 INFO - PROCESS | 1660 | 17:22:30 INFO - PROCESS | 1660 | [#7] imaging-desaturate Cycles:10 Average:114.60 Median:118.00 stddev:27.47 (23.3%) stddev-sans-first:27.77 17:22:30 INFO - PROCESS | 1660 | Values: 91.0 95.0 125.0 105.0 150.0 150.0 92.0 83.0 153.0 102.0 17:22:30 INFO - PROCESS | 1660 | 17:22:30 INFO - PROCESS | 1660 | [#8] json-parse-financial Cycles:10 Average:76.20 Median:91.00 stddev:17.23 (18.9%) stddev-sans-first:16.82 17:22:30 INFO - PROCESS | 1660 | Values: 57.0 58.0 65.0 90.0 93.0 92.0 93.0 94.0 60.0 60.0 17:22:30 INFO - PROCESS | 1660 | 17:22:30 INFO - PROCESS | 1660 | [#9] json-stringify-tinderbox Cycles:10 Average:89.20 Median:74.50 stddev:16.61 (22.3%) stddev-sans-first:15.75 17:22:30 INFO - PROCESS | 1660 | Values: 68.0 81.0 108.0 117.0 74.0 75.0 77.0 91.0 101.0 100.0 17:22:30 INFO - PROCESS | 1660 | 17:22:30 INFO - PROCESS | 1660 | [#10] stanford-crypto-aes Cycles:10 Average:91.50 Median:82.00 stddev:11.70 (14.3%) stddev-sans-first:12.38 17:22:30 INFO - PROCESS | 1660 | Values: 89.0 103.0 104.0 84.0 87.0 80.0 84.0 74.0 106.0 104.0 17:22:30 INFO - PROCESS | 1660 | 17:22:30 INFO - PROCESS | 1660 | [#11] stanford-crypto-ccm Cycles:10 Average:150.60 Median:150.50 stddev:19.06 (12.7%) stddev-sans-first:15.57 17:22:30 INFO - PROCESS | 1660 | Values: 116.0 146.0 147.0 151.0 146.0 155.0 150.0 158.0 194.0 143.0 17:22:30 INFO - PROCESS | 1660 | 17:22:30 INFO - PROCESS | 1660 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.40 Median:164.00 stddev:13.50 (8.2%) stddev-sans-first:12.77 17:22:30 INFO - PROCESS | 1660 | Values: 148.0 201.0 165.0 164.0 164.0 161.0 162.0 166.0 163.0 160.0 17:22:30 INFO - PROCESS | 1660 | 17:22:30 INFO - PROCESS | 1660 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.40 Median:78.50 stddev:9.55 (12.2%) stddev-sans-first:9.50 17:22:30 INFO - PROCESS | 1660 | Values: 65.0 62.0 67.0 65.0 71.0 77.0 86.0 84.0 87.0 80.0 17:22:30 INFO - PROCESS | 1660 | -------- Summary: end -------- 17:22:30 INFO - PROCESS | 1660 | 17:22:30 INFO - TEST-INFO | 1660: exit 0 17:22:30 INFO - TEST-OK | kraken | took 60432ms 17:22:30 INFO - SUITE-END | took 1175s 17:22:31 INFO - Completed test suite (00:19:35) 17:22:31 INFO - javascript benchmark 17:22:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7563.4504845113, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3562.5190599269094, 3571.71426285897, 3578.0875876651457, 3597.8201089945496, 3603.927921441571, 4903.926441103377, 6054.576179667414, 6064.605800622962, 6084.634921904678, 6156.384361563847, 6125.63246205228, 6128.0, 6162.106494558287, 6170.352113028135, 6218.160548325975, 5433.8369848904495, 5483.643563168382, 5500.202470641754, 5504.669719816804, 5564.1931919871595, 5530.6128571000045, 5533.391326954032, 5537.861553461155, 5543.750531226085, 5582.7766889324375, 4126.442930204429, 5130.410002849682, 5389.056915039852, 5517.337919449652, 5554.416786237448, 5369.24830523725, 5419.430959749228, 5462.808801691922, 5505.944940550623, 5587.636803607753, 5323.787048518055, 5446.0, 5447.346318441812, 5467.0, 5509.173623956399, 5926.822195334147, 6075.27096748393, 6123.142760013601, 6157.661328626924, 6172.197614310156, 6227.501799856001, 6253.437190652863, 6273.6235825850135, 6291.528135389818, 6393.2328120625825, 6493.285738568754, 6501.2198536175965, 6501.252355979075, 6515.087887695727, 6522.315156908504, 6164.137020817089, 6223.657698826608, 6229.034499652561, 6237.438630523275, 6249.312575616678, 6304.211973503312, 6331.620102793847, 6432.453241474527, 6454.128692626529, 6463.515236357198, 6330.4619107375465, 6334.239891213084, 6335.018072198816, 6360.045993101072, 6376.39424254695, 6443.226812782496, 6506.414422701885, 6512.869742605216, 6524.217093948661, 6553.148090748172, 6206.038064100072, 6219.440250377488, 6245.812625621239, 6251.687415629201, 6417.422431981144, 6385.712642931079, 6393.840153996187, 6394.680265986682, 6410.583312084699, 6434.774782882577, 5195.350581177353, 5198.792048318025, 5199.766010529535, 5209.088409528393, 5242.082635538765, 4514.413126293561, 4554.931676024862, 4571.177188106173, 4617.468991066004, 4686.133065382915, 6123.203983482119, 6160.845978850564, 6172.351903050115, 6191.473724733358, 6197.0, 6452.967735161388, 6462.3537646235, 6472.967635161794, 6478.514111441661, 6483.351664833479, 6049.153118563405, 6110.419510146529, 6121.4490695837585, 6124.11200375943, 6134.447899689049, 4739.526047395302, 5510.366307874566, 5696.145578163226, 5709.914351284734, 5751.079827227623, 6636.203655561363, 6654.101696271038, 6683.097781799394, 6688.3646053624825, 6704.698288577123, 6215.378462153749, 6232.657203853832, 6321.52588555851, 6327.019312006646, 6401.29585745577, 6640.767573134917, 6660.433863121689, 6666.899996500153, 6671.199456065303, 6689.130413046468, 5282.947170528246, 5478.506934375925, 6234.220722409699, 6411.134496842867, 6411.775587854403, 5287.418383977682, 5935.486450954979, 6381.106645069601, 6443.677816109082, 6490.383413575702, 5182.740862956762, 5360.946390536201, 6168.0, 6265.718042687999, 6311.589746666452, 5191.844244672666, 6263.154474145932, 6294.527910406646, 6311.747530098744, 6334.619922804646, 6063.363346848518, 6119.68535298207, 6134.773364742421, 6181.690915454299, 6246.968765156145, 5068.721220332917, 5214.661047031931, 6189.412005859436, 6205.0, 6312.936870631235, 6176.7838125665385, 6198.039303907902, 6208.2860471044605, 6215.96892015537, 6229.470495007975], "value": 5886.686005658406, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [485.47083678790125, 485.5921026337873, 494.8589651949195, 510.2499325990792, 534.3561008984175, 39799.602003979955, 43112.0, 43254.35118473225, 43444.565554344525, 43585.78207108964, 44106.77946610263, 44618.0, 44638.553614463846, 44791.77604111983, 44834.1033179337, 9567.473788941605, 9775.755606109833, 9794.559244834, 11579.594714185005, 11993.040556755439, 11752.236104653213, 11793.0, 11836.40817959103, 11854.940725296361, 11878.881211187863, 11707.824382634268, 11813.940930295337, 11836.112291578165, 11863.110266729987, 11875.821862672025, 11677.474513646906, 11767.235129716584, 11825.231359961575, 11830.171887967846, 11857.703557411047, 11781.762914893985, 11803.645890623295, 11854.525818967317, 11860.466279017379, 11867.881321186764, 39334.0, 39706.20587588261, 39720.404193937116, 39723.0, 39775.20449590992, 3999.4800675911947, 4029.49631296088, 4041.333180025292, 4109.301418758818, 4111.444954931076, 28662.713372866212, 28730.281742956384, 28874.422511549652, 29040.0, 31348.059558213292, 3264.9183770405693, 3456.0668619472603, 3461.0309113448275, 3498.073010652179, 3506.982465087658, 500595.0, 503905.0, 506668.0, 507537.0, 509309.45345273404, 59992.70003649954, 60231.397686023454, 60232.795344092876, 60248.79502409927, 60275.69862150661, 56060.439395606336, 59973.40026599678, 61069.08396374058, 61216.0, 61460.0, 16176.27206775698, 16491.09298988543, 16591.0, 16638.75041874373, 16686.082265475514, 12227.266364018187, 12509.812352814715, 14027.929860350632, 14065.929670351583, 14193.645158871112, 21995.890020549796, 22936.655950160428, 22996.65505017426, 23000.539989200122, 23012.539749204923, 7678.654460549177, 8187.976502937133, 8878.422902511316, 9099.317551183687, 9183.908160918305, 894.9507777072323, 900.9909900901038, 903.0, 915.4827522449795, 932.9626814927462, 25364.74635253624, 27598.0, 30964.845175774426, 32129.51805722916, 32170.19574510656, 1083.712816103733, 1091.8908109189017, 1105.8949399807007, 1142.7714457108611, 1227.8281040654315, 15142.79036693355, 16435.09606971604, 16518.75221871673, 16629.251683674265, 16705.33178672864, 665460.0, 665977.0, 669768.0, 672867.0, 674249.0, 65289.67355163194, 65903.0, 66466.0, 66560.0, 66572.33427665758, 1446.768517037269, 1482.2959094430275, 1498.243387089513, 1502.2038319690582, 1509.6527798606382, 30223.0, 30316.24209394783, 31423.214419638778, 31504.842475787475, 32052.0, 48363.516364837305, 49641.0, 49713.25430118479, 51532.484675152766, 54817.1777423331, 520263.0, 532532.0240392794, 562728.0, 567279.0, 570088.0, 35978.28043439169, 36691.266174675824, 41275.793621031706, 42253.78873105615, 42338.15323693571, 38100.0, 44957.325640116076, 45144.77427612841, 47063.05873882572, 47378.0, 31877.840610797724, 31894.36211275715, 32002.67997319997, 32470.512942306355, 32597.0, 31517.0, 31689.5246571297, 31890.68109318877, 32027.199320017186, 32350.514742279356, 36671.63328366789, 37274.25451491009, 37390.8130459346, 37565.248695026494, 38373.61626383701, 31684.0, 31943.36113277675, 32161.839190803898, 32935.67064329326, 33195.0, 24067.638985414884, 24829.751702483467, 25291.367715806515, 25524.744752552237, 26016.219513414628], "value": 21001.954412087474, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [401.1355528835359, 406.29913399386044, 411.4342778679316, 415.98128084236214, 435.7429116821077, 20827.0, 21753.456163595918, 21970.45073873154, 22006.00972956219, 22396.0, 22040.0, 22294.0, 22981.310560683163, 23041.884790576023, 23433.414164645892, 5923.815236952605, 6082.4221698938645, 6222.377762223764, 6304.085907543404, 6316.494680425555, 5927.377625349341, 6229.0033594624865, 6267.404596563342, 6272.0, 6277.68611569422, 5988.580799344048, 6121.204243448346, 6198.411150940676, 6225.2840923293725, 6230.065490176465, 6043.788467403664, 6118.847028824271, 6129.2032035835055, 6157.507399408059, 6286.245650521921, 4907.263910413467, 5436.565074794007, 5444.0, 5446.8910621787345, 5472.2338872557875, 26871.46257074867, 27389.863050684817, 27598.724012759816, 27991.86004069987, 28075.57886631702, 2788.219298596396, 2850.1164638961986, 2898.130560831742, 2899.217211352944, 2906.7529260012925, 14029.947753918497, 16348.264328105144, 16423.42518011864, 16475.58811029722, 17037.82962170375, 1881.8024107468655, 1936.3997160880172, 1942.1357495914324, 1943.494691380237, 1949.2495389275089, 129041.35479322543, 130192.0, 130684.0, 134719.32640336832, 143475.56524434726, 11091.894269604361, 11603.36181510025, 11869.821952670716, 12243.142979991315, 12306.384680765927, 16487.0, 16535.338586456648, 16589.751153732705, 16596.0, 16629.584260393585, 9030.819383612292, 9174.266058715286, 9250.259979201648, 9321.813563728687, 9330.0, 8924.821503569892, 8962.462252264884, 9009.954950225207, 9160.6793762218, 9176.311776616647, 4117.279476091672, 4209.915801683949, 4859.198232291703, 5007.674501157413, 5102.183650615883, 1644.4644015367057, 2056.845736569763, 2093.6964140199584, 2137.7648458669532, 2141.3575927221773, 1009.4397609326894, 1020.5866624017285, 1023.7542989682422, 1026.8921763214905, 1033.8811036730722, 21374.893125534272, 22201.333959981228, 22295.773732444548, 22648.207312743976, 22730.0, 1012.4583347908833, 1071.3304184884448, 1084.9096657858959, 1104.5195340027115, 1118.4743170709753, 10485.213608979357, 11685.824712629317, 11800.58697945585, 11808.645740627795, 11828.11289153317, 399347.0, 399452.0, 401819.0, 401934.0, 768416.0, 117860.0, 122971.0, 161142.0, 161664.0, 163724.3627563733, 1043.3531210649446, 1087.5704096881686, 1102.167863263231, 1113.3709454158375, 1122.4499995002552, 19226.7115993263, 19588.706169407757, 19858.50353741168, 19899.801001989796, 19961.800381995996, 55500.0, 56759.71620141873, 56971.0, 57156.14265785933, 57336.71331643315, 80744.0, 83338.0, 83503.0, 89871.55064224637, 92936.0, 8520.403571750167, 8574.828503429771, 8608.311335093053, 8981.326400519856, 8986.985053224766, 9416.246700263826, 12299.508019679113, 13099.869001309866, 13382.0, 13382.93308533451, 8890.511021893599, 11079.224454288122, 11243.269187502787, 11275.548978040786, 11487.0, 10171.338862973884, 10478.0, 10607.94696026515, 10645.946770266099, 10862.891371086187, 9747.561359738687, 11482.081433485468, 11487.25332853362, 11599.594014209462, 11887.524499020288, 7565.735199268, 8348.248657620843, 8552.273056790242, 8787.91212087871, 8789.604467799092, 7811.96094019549, 11022.897710228914, 11048.226624136234, 11138.55445782192, 11177.776444471228], "value": 10193.277209678417, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1298.6363818130924, 1441.9632047360442, 1491.4928924165788, 1494.6711723420851, 1498.220925118938, 7716.38268938485, 7958.442908996372, 8230.012398512172, 8703.52130632815, 8705.173008564185, 8191.303739182176, 8505.574721263944, 8508.95745521273, 8613.0, 8613.91386086139, 2361.102780943239, 2405.374602603327, 2409.5301416223842, 2414.6860908081926, 2424.6847909771705, 2362.4684445999596, 2386.2721869829693, 2397.5324811661735, 2406.3262286559793, 2409.277216834951, 2370.9051637934463, 2373.703287089114, 2400.291913885408, 2403.807695384374, 2408.2895545814026, 2354.164271683545, 2362.6692263083182, 2374.465745207332, 2387.415083304593, 2390.486045500226, 2384.7972922301624, 2385.7733515316017, 2401.7718316760056, 2414.9034038638433, 2420.515896820625, 8482.363822713321, 8630.697925572575, 8644.61099250529, 8684.2184203422, 8691.0, 5200.0, 5262.657927234717, 5331.360236771573, 5350.571954243651, 5356.089464790956, 3470.791752494833, 3472.965270347315, 3490.720742340632, 3597.388443964532, 3656.3784156693428, 1264.4120483974996, 1277.310252463678, 1307.9084464087518, 1315.1451556488264, 1321.2138777427415, 11051.005409513182, 11074.058705010084, 11136.164787640879, 11137.610183643614, 11164.330140191534, 4672.766361681903, 4759.738214398241, 4795.664303498791, 4843.612510999112, 4875.366202393666, 9672.08115229052, 9733.610655573839, 9743.756406089904, 9745.12293893553, 9755.853662195073, 5393.2449457076045, 5394.8381548552825, 5396.271503347077, 5404.351477822608, 5405.4324295949145, 4415.823367065345, 4498.190325741332, 4833.130036593377, 4875.488073752276, 4940.184869496565, 3965.4448377227213, 4040.5151381834366, 4075.0, 4106.1582375612925, 4109.671226301889, 1279.9104062715521, 1286.7619490394306, 1293.741251749635, 1298.9997701769773, 1303.89568834493, 923.2798417234568, 928.6656803550717, 928.800307933784, 935.4527601353285, 960.4141473701177, 5665.915011274834, 5729.942700573024, 5894.616849904743, 6066.029435290332, 6083.087536869417, 568.2186992884783, 572.8854229154185, 582.5921854701726, 587.7149582452593, 591.1251348004977, 3821.178446633987, 3822.7515211511163, 3856.0, 3929.410588411761, 3938.448617193595, 9782.853257201148, 10293.43386113771, 10299.9485002576, 10309.74225644365, 10358.689239322832, 10238.53926573306, 10270.024347686958, 10281.280310378203, 10291.742706432398, 10295.227857910537, 7822.530648161129, 8114.350851931831, 8134.430589858653, 8169.5098294102545, 8177.141400152898, 4730.455997560324, 5524.06090964545, 5575.581831362745, 5643.435656434323, 5745.712714364348, 8126.0, 9190.172884440433, 9414.811703765748, 9481.146696797321, 9493.430394176372, 9272.869507157731, 9400.247980161434, 9426.0, 9535.666251681158, 9699.321047526888, 7171.390431813145, 7343.963280183565, 7377.631118443949, 7452.06849143857, 7460.850782984201, 7047.7666408379355, 8252.257296843312, 8263.462874913352, 8272.669093236444, 8287.83424331498, 6901.275366086488, 7018.192907815666, 7154.678039488132, 7237.167725711611, 7265.963670181826, 6862.988036356694, 7002.8949565757575, 7013.649317534205, 7092.929070709227, 7207.207207207098, 6455.483561315177, 6506.739730410731, 6513.934860651333, 6597.571157874723, 6603.372679595431, 5045.167547354646, 5057.418396884258, 5088.516590923857, 5105.183170692671, 5124.256982737407, 5055.140626093499, 5098.923516147335, 5136.2552429896705, 5162.9483705162465, 5217.686938783685], "value": 4484.028315753403, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [293.89419808868803, 356.80910712768673, 367.1358947081962, 384.53855373551744, 387.8661861657727, 5502.202180683808, 5566.972165139168, 5677.914831277534, 5682.522544138528, 5784.496031031925, 4226.738694798283, 4674.719516828993, 5557.666540007603, 5594.440555944414, 5625.5499560035205, 3306.8346582670893, 4150.0119913662265, 4358.934615980755, 4406.5593440656, 4467.955320446787, 3680.392735198689, 4150.252954468195, 4345.369921361401, 4442.400275962736, 4453.574856046067, 4168.958310416902, 4300.483941926973, 4359.7602131882895, 4370.32259999699, 4388.780560971954, 4364.301711726124, 4383.802728877208, 4393.450818647669, 4415.470143582791, 4432.911341773147, 3886.8250928708276, 3931.722190288168, 3936.488256526633, 3942.7831469269177, 3949.328614135604, 4729.456112547033, 4823.300621409911, 5369.033573956686, 5394.757235924393, 5469.917951230735, 5029.19532874742, 5063.670861393997, 5091.694498330112, 5097.23541468779, 5105.923411148836, 5043.67216130954, 5471.616986810925, 5975.312839023524, 5986.4612184903135, 6048.334683184846, 3326.750493712981, 3903.3754599264266, 4069.6337329640473, 4074.225897079575, 4097.405876147942, 5143.562797162245, 5821.941780582182, 6005.549583781234, 6255.906161407583, 6260.5304602154565, 3820.815547180383, 3978.244133614604, 4087.141700242923, 4099.262132816062, 4122.793860306972, 3626.7642603230706, 4150.481189851269, 4164.708470407103, 4180.540140584533, 4184.4141820145205, 4087.6525495332635, 4517.322401639781, 4898.461169271377, 4904.950950490521, 4913.469563174967, 5534.861628459321, 5657.670447444847, 5997.850053748692, 6026.397360263939, 6032.909506357408, 1156.248438514972, 1225.221984040123, 1365.9248741319125, 1366.6924941887996, 1366.924819134957, 836.2640876029177, 859.7361878039272, 907.5280853955906, 915.4873270968279, 919.8160367926307, 4486.330633273197, 4758.3100450434495, 4972.627052970969, 5119.846404607868, 5276.868078297996, 5273.525382715574, 5835.328937172279, 5863.508009096117, 5882.794102206402, 5905.645661260338, 4629.814807407666, 4949.802007919715, 5243.423223445494, 5307.389650190277, 5330.680159190461, 2662.8535430551506, 3063.5711000459955, 3072.032309822399, 3077.19983809474, 3082.830444325584, 5262.868483276114, 5504.311961004874, 5522.8678120985105, 5952.7023648817385, 6104.603200791934, 5659.377468478464, 5663.830085097454, 6293.748250069946, 6315.810525684236, 6322.743786949724, 5120.795168193231, 5752.1526844212385, 6184.938150618526, 6316.05259211122, 6354.872902541923, 5731.914021289601, 5925.259342582177, 6073.696315184311, 6184.443400094013, 6207.782727604512, 5548.722563871871, 6030.7286172121485, 6063.242094738158, 6070.1501789750355, 6097.817065488042, 5270.841874743764, 6179.620845974422, 6210.975189093749, 6357.427831495188, 6383.585066970632, 3822.2355528893777, 3888.6889048875455, 3991.501062367204, 4005.2189822984237, 4031.254217969685, 5149.794008239628, 5322.498425739896, 6109.7861574844665, 6125.938740612537, 6128.233970753656, 4914.778834952364, 5541.972290138685, 5828.0, 5901.822945311647, 5903.498202652822, 5011.569388846479, 5347.919781203207, 5728.742206600797, 5910.881782364415, 5911.231539899785, 5078.08594453002, 5540.252065971043, 5703.7433315500075, 5786.42135786418, 5834.883302333845, 5409.972950135224, 5546.029444847055, 5660.405657406078, 5906.021482803309, 5925.348211696623, 5081.898362032664, 5318.867028324168, 5789.2763404574425, 5803.65178089316, 5833.387494313036], "value": 4288.34572797499, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [733.1605311917857, 741.417986880299, 783.5612057247941, 872.6334939325483, 873.8732883731857, 15639.452619158332, 16343.754843677325, 16379.59051023725, 16386.59033524161, 16437.09595972221, 15062.39750409985, 15172.089674619503, 15179.620509487268, 15198.164100974442, 15232.69534609307, 700.3696672994321, 712.6899798587605, 865.5412631305403, 871.8735783311416, 872.7774417523523, 820.5199958024563, 834.2783492279165, 847.4533925617973, 861.9051904290523, 864.8702694595798, 910.0353625157321, 935.8596210568435, 936.1761649748212, 939.037486576258, 939.6194541210821, 899.8560230363111, 911.8677791720197, 925.9583318750672, 929.6606738540452, 933.9719808405758, 670.8222321084917, 689.1179290508157, 697.0380874393305, 698.2912343970822, 699.1121275979477, 14889.70220595593, 15454.922725386301, 15751.684966300725, 16260.674786504203, 16503.09232992185, 14033.85966140346, 14221.21783301918, 14315.498957536436, 14354.0, 14512.92743536286, 11755.588554400554, 11897.28616283017, 11924.0, 12018.218815776947, 12098.395080245953, 3776.206996530732, 3893.980530097331, 3900.2589507993675, 3913.4716813230134, 3935.74417662851, 86952.0, 110019.29093326123, 111902.88097119005, 112069.0, 113901.0, 11158.721031974266, 11272.943635281936, 11458.59894903691, 11799.0, 12295.385230738428, 19931.501712457306, 24186.637200442008, 24517.63223551648, 24551.0, 24583.754162458146, 8200.671973121009, 8208.753737387888, 8242.29940455068, 8352.0812710603, 8362.121977192308, 4366.170427618742, 4407.977960110179, 4510.548945105464, 4531.682782205214, 4691.225947718656, 3771.283456143324, 3816.2558301131567, 3829.2532956073856, 3865.091703449687, 3869.5356557212754, 694.9131358580178, 697.8953157026486, 698.0785363320368, 705.1467724053849, 712.9251428599922, 680.4454369688688, 704.3132945378215, 711.3882061427169, 714.9034880291094, 750.3434494817035, 8772.736817895473, 10488.4231367274, 10682.768230376176, 10878.564857405616, 10937.343759374464, 645.6965226343611, 697.7057558229512, 704.728679458402, 711.6192836832373, 724.822418507469, 4743.193657078236, 5133.537981581676, 5142.588592912558, 5147.588192944556, 5167.689938603696, 94033.0, 95449.52275238579, 107414.0, 107734.0, 112894.0, 83050.16949830545, 93135.0, 96051.0, 96850.51574742081, 97823.0, 78574.607126964, 82372.9999999988, 82541.0, 83384.0, 83685.58157209175, 9789.162640258744, 9792.461414622265, 9795.412275263507, 9844.803103938024, 9871.160951319218, 46239.76880115578, 48146.62973591815, 50222.0, 50937.72655683637, 52621.47378526166, 55365.0, 66498.33501665115, 72213.27786722066, 73008.634956827, 74724.25275747172, 11010.0, 12139.02887768994, 13654.658633534242, 13844.238566878917, 14140.080894742221, 14270.71458570802, 15114.622134446287, 15319.234038298264, 15901.443449479213, 16111.436099736453, 13784.931075344559, 13821.239831809347, 14100.858991409954, 14272.785908211592, 14281.785773213618, 13642.522511712043, 13695.794563081761, 14206.063249295132, 14414.0, 14646.853531464549, 10800.626382640316, 13362.732745344843, 13727.313634318443, 14343.856561434251, 14536.746804245358, 8413.873791893004, 10288.176945844465, 10355.223358248011, 10552.366857988545, 10753.623623173151, 9141.77145571366, 9184.770380740534, 10588.841167382341, 10852.620158294421, 11476.942615286871], "value": 7724.499971685697, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1558.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 118.0, 136.0, 172.0, 117.0, 117.0, 119.0, 117.0, 117.0, 119.0], "value": 124.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 126.0, 145.0, 148.0, 109.0, 111.0, 108.0, 110.0, 129.0, 107.0], "value": 122.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 203.0, 197.0, 198.0, 197.0, 194.0, 197.0, 196.0, 198.0, 195.0], "value": 197.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 59.0, 60.0, 91.0, 52.0, 73.0, 85.0, 82.0, 95.0, 50.0], "value": 72.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 67.0, 85.0, 71.0, 69.0, 71.0, 81.0, 69.0, 72.0, 82.0], "value": 74.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 88.0, 92.0, 89.0, 102.0, 88.0, 82.0, 133.0, 97.0, 99.0], "value": 96.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 102.0, 100.0, 102.0, 110.0, 171.0, 103.0, 100.0, 101.0, 101.0], "value": 110.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 95.0, 125.0, 105.0, 150.0, 150.0, 92.0, 83.0, 153.0, 102.0], "value": 114.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 58.0, 65.0, 90.0, 93.0, 92.0, 93.0, 94.0, 60.0, 60.0], "value": 76.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 81.0, 108.0, 117.0, 74.0, 75.0, 77.0, 91.0, 101.0, 100.0], "value": 89.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 103.0, 104.0, 84.0, 87.0, 80.0, 84.0, 74.0, 106.0, 104.0], "value": 91.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 146.0, 147.0, 151.0, 146.0, 155.0, 150.0, 158.0, 194.0, 143.0], "value": 150.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 201.0, 165.0, 164.0, 164.0, 161.0, 162.0, 166.0, 163.0, 160.0], "value": 165.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 62.0, 67.0, 65.0, 71.0, 77.0, 86.0, 84.0, 87.0, 80.0], "value": 74.4, "unit": "score"}]}]} 17:22:31 INFO - Return code: 0 17:22:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:22:31 ERROR - # TBPL SUCCESS # 17:22:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:22:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:22:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:22:31 INFO - 2017-04-02 17:22:31.271 screenresolution[1665:10540] starting screenresolution argv=screenresolution get 17:22:31 INFO - 2017-04-02 17:22:31.274 screenresolution[1665:10540] Display 0: 1600x1200x32@60 17:22:31 INFO - 2017-04-02 17:22:31.280 screenresolution[1666:10543] starting screenresolution argv=screenresolution list 17:22:31 INFO - Available Modes on Display 0 17:22:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:22:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:22:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:22:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:22:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:22:31 INFO - Intel Iris: 17:22:31 INFO - Chipset Model: Intel Iris 17:22:31 INFO - Type: GPU 17:22:31 INFO - Bus: Built-In 17:22:31 INFO - VRAM (Dynamic, Max): 1536 MB 17:22:31 INFO - Vendor: Intel (0x8086) 17:22:31 INFO - Device ID: 0x0a2e 17:22:31 INFO - Revision ID: 0x0009 17:22:31 INFO - Displays: 17:22:31 INFO - Display: 17:22:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:22:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:22:31 INFO - Display Serial Number: 1600x1200 60 17:22:31 INFO - Main Display: Yes 17:22:31 INFO - Mirror: Off 17:22:31 INFO - Online: Yes 17:22:31 INFO - Rotation: Supported 17:22:31 INFO - Return code: 0 17:22:31 INFO - Running post-action listener: _resource_record_post_action 17:22:31 INFO - [mozharness: 2017-04-03 00:22:31.514386Z] Finished run-tests step (success) 17:22:31 INFO - Running post-run listener: _resource_record_post_run 17:22:31 INFO - Total resource usage - Wall time: 1190s; CPU: 54.0%; Read bytes: 42432512; Write bytes: 423781376; Read time: 424; Write time: 1601 17:22:31 INFO - TinderboxPrint: CPU usage
54.3% 17:22:31 INFO - TinderboxPrint: I/O read bytes / time
42,432,512 / 424 17:22:31 INFO - TinderboxPrint: I/O write bytes / time
423,781,376 / 1,601 17:22:31 INFO - TinderboxPrint: CPU idle
2,175.5 (45.7%) 17:22:31 INFO - TinderboxPrint: CPU system
960.3 (20.2%) 17:22:31 INFO - TinderboxPrint: CPU user
1,625.9 (34.1%) 17:22:31 INFO - TinderboxPrint: Swap in / out
163,037,184 / 0 17:22:31 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 109836800; Write bytes: 103888896; Read time: 9874; Write time: 252 17:22:31 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 41449472; Write bytes: 299363328; Read time: 410; Write time: 1292 17:22:31 INFO - Running post-run listener: _upload_blobber_files 17:22:31 INFO - Blob upload gear active. 17:22:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:22:31 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 17:22:31 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-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:22:31 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-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:22:32 INFO - (blobuploader) - INFO - Open directory for files ... 17:22:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:22:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:22:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:22:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:22:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:22:33 INFO - (blobuploader) - INFO - Done attempting. 17:22:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:22:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:22:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:22:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:22:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:22:34 INFO - (blobuploader) - INFO - Done attempting. 17:22:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:22:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:22:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:22:35 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:22:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:22:35 INFO - (blobuploader) - INFO - Done attempting. 17:22:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:22:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:22:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:22:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:22:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:22:37 INFO - (blobuploader) - INFO - Done attempting. 17:22:37 INFO - (blobuploader) - INFO - Iteration through files over. 17:22:37 INFO - Return code: 0 17:22:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:22:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:22:37 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/996d0b07b7189cccbc320fe046bc4b5b073c0e1829d72bb8700bdbf84c0e4d9f96dd16dcfc39afb13184084c69c2b53cc2d607a3a4e81f94bfc2c1b3c964a06f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/83486cbf9462b45c6876bfb10789a4a9292cacb2f1c706c52a5a49f9e779675af8c3fd2feec9c18fbb6d16db07a0e810f0d622adeacb078d1936d1b1805c8233", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6f7e77e395d3a1c99b5961534a8fa4f7f4d84103c448021ae4d4cb311ff269a7e37e7f95004dd5b507086603c4a2627f38f39f04754ea081964b33ae0e40d2de", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/678d846b75a7ecc35a2af41895bed1d862d9df76b9a332a4d00a525e2b0f4ca0815702276ba3a3b581ca8948559fb99b3f47a5287195ef2b642a9ce17103ed20"} 17:22:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:22:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:22:37 INFO - Contents: 17:22:37 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/996d0b07b7189cccbc320fe046bc4b5b073c0e1829d72bb8700bdbf84c0e4d9f96dd16dcfc39afb13184084c69c2b53cc2d607a3a4e81f94bfc2c1b3c964a06f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/83486cbf9462b45c6876bfb10789a4a9292cacb2f1c706c52a5a49f9e779675af8c3fd2feec9c18fbb6d16db07a0e810f0d622adeacb078d1936d1b1805c8233", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6f7e77e395d3a1c99b5961534a8fa4f7f4d84103c448021ae4d4cb311ff269a7e37e7f95004dd5b507086603c4a2627f38f39f04754ea081964b33ae0e40d2de", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/678d846b75a7ecc35a2af41895bed1d862d9df76b9a332a4d00a525e2b0f4ca0815702276ba3a3b581ca8948559fb99b3f47a5287195ef2b642a9ce17103ed20"} 17:22:37 INFO - Running post-run listener: copy_logs_to_upload_dir 17:22:37 INFO - Copying logs to upload dir... 17:22:37 INFO - mkdir: /builds/slave/test/build/upload/logs 17:22:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1253.642423 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2017-04-02 17:22:37.345687) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-02 17:22:37.349332) ========= 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.E6YrsJP9nX/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.COEmlzNdFr/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/996d0b07b7189cccbc320fe046bc4b5b073c0e1829d72bb8700bdbf84c0e4d9f96dd16dcfc39afb13184084c69c2b53cc2d607a3a4e81f94bfc2c1b3c964a06f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/83486cbf9462b45c6876bfb10789a4a9292cacb2f1c706c52a5a49f9e779675af8c3fd2feec9c18fbb6d16db07a0e810f0d622adeacb078d1936d1b1805c8233", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6f7e77e395d3a1c99b5961534a8fa4f7f4d84103c448021ae4d4cb311ff269a7e37e7f95004dd5b507086603c4a2627f38f39f04754ea081964b33ae0e40d2de", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/678d846b75a7ecc35a2af41895bed1d862d9df76b9a332a4d00a525e2b0f4ca0815702276ba3a3b581ca8948559fb99b3f47a5287195ef2b642a9ce17103ed20"} build_url:https://queue.taskcluster.net/v1/task/O2qzZRmjQFOtlwqSTXzZyw/artifacts/public/build/firefox-53.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011230 build_url: 'https://queue.taskcluster.net/v1/task/O2qzZRmjQFOtlwqSTXzZyw/artifacts/public/build/firefox-53.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/996d0b07b7189cccbc320fe046bc4b5b073c0e1829d72bb8700bdbf84c0e4d9f96dd16dcfc39afb13184084c69c2b53cc2d607a3a4e81f94bfc2c1b3c964a06f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/83486cbf9462b45c6876bfb10789a4a9292cacb2f1c706c52a5a49f9e779675af8c3fd2feec9c18fbb6d16db07a0e810f0d622adeacb078d1936d1b1805c8233", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6f7e77e395d3a1c99b5961534a8fa4f7f4d84103c448021ae4d4cb311ff269a7e37e7f95004dd5b507086603c4a2627f38f39f04754ea081964b33ae0e40d2de", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/678d846b75a7ecc35a2af41895bed1d862d9df76b9a332a4d00a525e2b0f4ca0815702276ba3a3b581ca8948559fb99b3f47a5287195ef2b642a9ce17103ed20"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-02 17:22:37.384227) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-02 17:22:37.384553) ========= 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.E6YrsJP9nX/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.COEmlzNdFr/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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.005143 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-02 17:22:37.432979) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-02 17:22:37.433290) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-02 17:22:37.433612) ========= ========= Total master_lag: 0.17 =========