builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0070 starttime: 1481838318.63 results: success (0) buildid: 20161215114101 builduid: 62f769bde13a40839f0eb04650c194ac revision: f3353c8bb6f3de41df3e889da27115d6e29a7e93 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-15 13:45:18.635019) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-15 13:45:18.635459) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-15 13:45:18.635738) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-15 13:45:18.660556) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 13:45:18.660951) ========= 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.bplbtlSfjJ/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.fgfEXIOfNl/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-15 13:45:18-- 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% 20.1M=0.001s 2016-12-15 13:45:18 (20.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.102431 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 13:45:18.786707) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 13:45:18.790052) ========= 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.bplbtlSfjJ/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.fgfEXIOfNl/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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.019705 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 13:45:18.838693) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-15 13:45:18.842080) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev f3353c8bb6f3de41df3e889da27115d6e29a7e93 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev f3353c8bb6f3de41df3e889da27115d6e29a7e93 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.bplbtlSfjJ/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.fgfEXIOfNl/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-15 13:45:18,917 truncating revision to first 12 chars 2016-12-15 13:45:18,917 Setting DEBUG logging. 2016-12-15 13:45:18,918 attempt 1/10 2016-12-15 13:45:18,918 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/f3353c8bb6f3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-15 13:45:20,328 unpacking tar archive at: mozilla-aurora-f3353c8bb6f3/testing/mozharness/ program finished with exit code 0 elapsedTime=1.685396 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-15 13:45:20.553931) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-15 13:45:20.554659) ========= script_repo_revision: f3353c8bb6f3de41df3e889da27115d6e29a7e93 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-15 13:45:20.555166) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-15 13:45:20.555435) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-15 13:45:20.575419) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-12-15 13:45:20.575743) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.bplbtlSfjJ/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.fgfEXIOfNl/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 13:45:20 INFO - MultiFileLogger online at 20161215 13:45:20 in /builds/slave/test 13:45:20 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 13:45:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:45:20 INFO - {'append_to_log': False, 13:45:20 INFO - 'base_work_dir': '/builds/slave/test', 13:45:20 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 13:45:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:45:20 INFO - 'branch': 'Mozilla-Aurora', 13:45:20 INFO - 'buildbot_json_path': 'buildprops.json', 13:45:20 INFO - 'config_files': ('talos/mac_config.py',), 13:45:20 INFO - 'default_actions': ('clobber', 13:45:20 INFO - 'read-buildbot-config', 13:45:20 INFO - 'download-and-extract', 13:45:20 INFO - 'populate-webroot', 13:45:20 INFO - 'create-virtualenv', 13:45:20 INFO - 'install', 13:45:20 INFO - 'run-tests'), 13:45:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:45:20 INFO - 'download_minidump_stackwalk': True, 13:45:20 INFO - 'download_symbols': 'ondemand', 13:45:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:45:20 INFO - 'tooltool.py': '/tools/tooltool.py', 13:45:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:45:20 INFO - '/tools/misc-python/virtualenv.py')}, 13:45:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:45:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:45:20 INFO - 'installer_path': 'installer.exe', 13:45:20 INFO - 'log_level': 'info', 13:45:20 INFO - 'log_name': 'talos', 13:45:20 INFO - 'log_to_console': True, 13:45:20 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:45:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:45:20 INFO - 'opt_config_files': (), 13:45:20 INFO - 'pip_index': False, 13:45:20 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:45:20 INFO - 'cmd': ('bash', 13:45:20 INFO - '-c', 13:45:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:45:20 INFO - 'enabled': True, 13:45:20 INFO - 'halt_on_failure': False, 13:45:20 INFO - 'name': 'check_screen_resolution'},), 13:45:20 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:45:20 INFO - 'cmd': ('bash', 13:45:20 INFO - '-c', 13:45:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:45:20 INFO - 'enabled': True, 13:45:20 INFO - 'halt_on_failure': False, 13:45:20 INFO - 'name': 'check_screen_resolution'},), 13:45:20 INFO - 'run_cmd_checks_enabled': True, 13:45:20 INFO - 'sps_profile': False, 13:45:20 INFO - 'sps_profile_interval': 0, 13:45:20 INFO - 'suite': 'dromaeojs', 13:45:20 INFO - 'system_bits': '32', 13:45:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:45:20 INFO - 'title': 't-yosemite-r7-0070', 13:45:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:45:20 INFO - 'use_talos_json': True, 13:45:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:45:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:45:20 INFO - 'work_dir': 'build'} 13:45:20 INFO - [mozharness: 2016-12-15 21:45:20.778401Z] Running clobber step. 13:45:20 INFO - Running pre-action listener: _resource_record_pre_action 13:45:20 INFO - Running main action method: clobber 13:45:20 INFO - rmtree: /builds/slave/test/build 13:45:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:45:22 INFO - Running post-action listener: _resource_record_post_action 13:45:22 INFO - [mozharness: 2016-12-15 21:45:22.324275Z] Finished clobber step (success) 13:45:22 INFO - [mozharness: 2016-12-15 21:45:22.324412Z] Running read-buildbot-config step. 13:45:22 INFO - Running pre-action listener: _resource_record_pre_action 13:45:22 INFO - Running main action method: read_buildbot_config 13:45:22 INFO - Using buildbot properties: 13:45:22 INFO - { 13:45:22 INFO - "project": "", 13:45:22 INFO - "product": "firefox", 13:45:22 INFO - "script_repo_revision": "production", 13:45:22 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 13:45:22 INFO - "repository": "", 13:45:22 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 13:45:22 INFO - "buildid": "20161215114101", 13:45:22 INFO - "pgo_build": "False", 13:45:22 INFO - "basedir": "/builds/slave/test", 13:45:22 INFO - "buildnumber": 17, 13:45:22 INFO - "slavename": "t-yosemite-r7-0070", 13:45:22 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 13:45:22 INFO - "platform": "yosemite_r7", 13:45:22 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 13:45:22 INFO - "branch": "mozilla-aurora", 13:45:22 INFO - "repo_path": "releases/mozilla-aurora", 13:45:22 INFO - "revision": "f3353c8bb6f3de41df3e889da27115d6e29a7e93", 13:45:22 INFO - "stage_platform": "macosx64", 13:45:22 INFO - "builduid": "62f769bde13a40839f0eb04650c194ac", 13:45:22 INFO - "slavebuilddir": "test" 13:45:22 INFO - } 13:45:22 INFO - Found installer url https://queue.taskcluster.net/v1/task/M5CbLo8uSS-RC6QtkKztxw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 13:45:22 INFO - Running post-action listener: _resource_record_post_action 13:45:22 INFO - [mozharness: 2016-12-15 21:45:22.327804Z] Finished read-buildbot-config step (success) 13:45:22 INFO - [mozharness: 2016-12-15 21:45:22.327965Z] Running download-and-extract step. 13:45:22 INFO - Running pre-action listener: _resource_record_pre_action 13:45:22 INFO - Running main action method: download_and_extract 13:45:22 INFO - mkdir: /builds/slave/test/build/tests 13:45: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')]} 13:45:22 INFO - trying https://queue.taskcluster.net/v1/task/M5CbLo8uSS-RC6QtkKztxw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 13:45:22 INFO - Downloading https://queue.taskcluster.net/v1/task/M5CbLo8uSS-RC6QtkKztxw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 13:45:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/M5CbLo8uSS-RC6QtkKztxw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 13:45:23 INFO - Downloaded 1199 bytes. 13:45:23 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 13:45:23 INFO - Using the following test package requirements: 13:45:23 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 13:45:23 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 13:45:23 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 13:45:23 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 13:45:23 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 13:45:23 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 13:45:23 INFO - u'jsshell-mac.zip'], 13:45:23 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 13:45:23 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 13:45:23 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 13:45:23 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 13:45:23 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 13:45:23 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 13:45:23 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 13:45:23 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 13:45:23 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 13:45:23 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 13:45:23 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 13:45:23 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 13:45:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/M5CbLo8uSS-RC6QtkKztxw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 13:45:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/M5CbLo8uSS-RC6QtkKztxw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 13:45:23 INFO - Fetch https://queue.taskcluster.net/v1/task/M5CbLo8uSS-RC6QtkKztxw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 13:45:24 INFO - Http code: 200 13:45:24 INFO - via: 1.1 423912f2b1c5569e2fa5017167c61fa0.cloudfront.net (CloudFront) 13:45:24 INFO - x-amz-cf-id: QMpK6e7Ws7IP9jXDFkbkUdH8YtiRlNkzPYIFraWYMUFC_6kU3utVxA== 13:45:24 INFO - x-amz-version-id: .l53yKkbxTmuOOwC9WRucx4VW_Fv6hm6 13:45:24 INFO - Expected file size: 16280961 13:45:24 INFO - Obtained file size: 16280961 13:45:31 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 13:45:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/M5CbLo8uSS-RC6QtkKztxw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 13:45:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/M5CbLo8uSS-RC6QtkKztxw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 13:45:31 INFO - Fetch https://queue.taskcluster.net/v1/task/M5CbLo8uSS-RC6QtkKztxw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 13:45:31 INFO - Http code: 200 13:45:31 INFO - via: 1.1 ccaa055c9a26ce13da709353563e04a5.cloudfront.net (CloudFront) 13:45:31 INFO - x-amz-cf-id: K1P-iEPmMLzTM4Fjewo6uBCj6JRDzhV_859vLG3dZzXfiTQyRg9E3w== 13:45:31 INFO - x-amz-version-id: .l53yKkbxTmuOOwC9WRucx4VW_Fv6hm6 13:45:32 INFO - Expected file size: 16280961 13:45:32 INFO - Obtained file size: 16280961 13:45:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/M5CbLo8uSS-RC6QtkKztxw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 13:45:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/M5CbLo8uSS-RC6QtkKztxw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 13:45:38 INFO - Fetch https://queue.taskcluster.net/v1/task/M5CbLo8uSS-RC6QtkKztxw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 13:45:39 INFO - Http code: 200 13:45:39 INFO - via: 1.1 20f1c35f343f4b271ae8dcacfd7ea0e9.cloudfront.net (CloudFront) 13:45:39 INFO - x-amz-cf-id: 0YKl3vSf_jelK6H8DMM2NP_-7GWAV7HlROUqOhOrGS0l90jK4btTrg== 13:45:39 INFO - x-amz-version-id: d3HGbgVs5AS11EgO0Cu3gYv3VDj31jyF 13:45:40 INFO - Expected file size: 14409038 13:45:40 INFO - Obtained file size: 14409038 13:45:41 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')]} 13:45:41 INFO - trying https://queue.taskcluster.net/v1/task/M5CbLo8uSS-RC6QtkKztxw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 13:45:41 INFO - Downloading https://queue.taskcluster.net/v1/task/M5CbLo8uSS-RC6QtkKztxw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 13:45:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/M5CbLo8uSS-RC6QtkKztxw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 13:45:43 INFO - Downloaded 109723243 bytes. 13:45:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/M5CbLo8uSS-RC6QtkKztxw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 13:45:43 INFO - mkdir: /builds/slave/test/properties 13:45:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:45:43 INFO - Writing to file /builds/slave/test/properties/build_url 13:45:43 INFO - Contents: 13:45:43 INFO - build_url:https://queue.taskcluster.net/v1/task/M5CbLo8uSS-RC6QtkKztxw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 13:45:44 INFO - Running post-action listener: _resource_record_post_action 13:45:44 INFO - Running post-action listener: set_extra_try_arguments 13:45:44 INFO - [mozharness: 2016-12-15 21:45:44.521638Z] Finished download-and-extract step (success) 13:45:44 INFO - [mozharness: 2016-12-15 21:45:44.521791Z] Running populate-webroot step. 13:45:44 INFO - Running pre-action listener: _resource_record_pre_action 13:45:44 INFO - Running main action method: populate_webroot 13:45:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:45:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:45:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:45:44 INFO - '--tppagecycles', 13:45:44 INFO - '7'], 13:45:44 INFO - 'tests': ['tsvgm']}}, 13:45:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:45:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:45:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:45:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:45:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:45:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:45:44 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:45:44 INFO - 'talos_options': ['--disable-e10s'], 13:45:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:45:44 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:45:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:45:44 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:45:44 INFO - 'talos_options': ['--disable-e10s'], 13:45:44 INFO - 'tests': ['damp', 'tps']}, 13:45:44 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:45:44 INFO - 'tests': ['damp', 'tps']}, 13:45:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:45:44 INFO - 'tests': ['dromaeo_dom']}, 13:45:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:45:44 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:45:44 INFO - 'tests': ['basic_compositor_video']}, 13:45:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:45:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:45:44 INFO - 'tests': ['a11yr', 13:45:44 INFO - 'ts_paint', 13:45:44 INFO - 'tpaint', 13:45:44 INFO - 'sessionrestore', 13:45:44 INFO - 'sessionrestore_no_auto_restore', 13:45:44 INFO - 'tabpaint']}, 13:45:44 INFO - 'other-e10s': {'tests': ['a11yr', 13:45:44 INFO - 'ts_paint', 13:45:44 INFO - 'tpaint', 13:45:44 INFO - 'sessionrestore', 13:45:44 INFO - 'sessionrestore_no_auto_restore', 13:45:44 INFO - 'tabpaint']}, 13:45:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:45:44 INFO - 'ts_paint', 13:45:44 INFO - 'tpaint', 13:45:44 INFO - 'sessionrestore', 13:45:44 INFO - 'sessionrestore_no_auto_restore', 13:45:44 INFO - 'tabpaint']}, 13:45:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:45:44 INFO - 'ts_paint', 13:45:44 INFO - 'tpaint', 13:45:44 INFO - 'sessionrestore', 13:45:44 INFO - 'sessionrestore_no_auto_restore', 13:45:44 INFO - 'tabpaint']}, 13:45:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:45:44 INFO - 'tests': ['a11yr', 13:45:44 INFO - 'ts_paint', 13:45:44 INFO - 'tpaint', 13:45:44 INFO - 'sessionrestore', 13:45:44 INFO - 'sessionrestore_no_auto_restore', 13:45:44 INFO - 'tabpaint']}, 13:45:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:45:44 INFO - 'tests': ['a11yr', 13:45:44 INFO - 'ts_paint', 13:45:44 INFO - 'tpaint', 13:45:44 INFO - 'sessionrestore', 13:45:44 INFO - 'sessionrestore_no_auto_restore', 13:45:44 INFO - 'tabpaint']}, 13:45:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:45:44 INFO - 'tests': ['tsvgx', 13:45:44 INFO - 'tsvgr_opacity', 13:45:44 INFO - 'tart', 13:45:44 INFO - 'tscrollx', 13:45:44 INFO - 'cart']}, 13:45:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:45:44 INFO - 'tsvgr_opacity', 13:45:44 INFO - 'tart', 13:45:44 INFO - 'tscrollx', 13:45:44 INFO - 'cart']}, 13:45:44 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:45:44 INFO - 'talos_options': ['--disable-e10s'], 13:45:44 INFO - 'tests': ['tp5o']}, 13:45:44 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:45:44 INFO - 'tests': ['tp5o']}, 13:45:44 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:45:44 INFO - 'talos_options': ['--disable-e10s', 13:45:44 INFO - '--xperf_path', 13:45:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:45:44 INFO - 'tests': ['tp5n']}, 13:45:44 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:45:44 INFO - 'talos_options': ['--xperf_path', 13:45:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:45:44 INFO - 'tests': ['tp5n']}}, 13:45:44 INFO - 'talos.zip': {'path': '', 13:45:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:45:44 INFO - Running post-action listener: _resource_record_post_action 13:45:44 INFO - [mozharness: 2016-12-15 21:45:44.540769Z] Finished populate-webroot step (success) 13:45:44 INFO - [mozharness: 2016-12-15 21:45:44.540887Z] Running create-virtualenv step. 13:45:44 INFO - Running pre-action listener: _resource_record_pre_action 13:45:44 INFO - Running main action method: create_virtualenv 13:45:44 INFO - Creating virtualenv /builds/slave/test/build/venv 13:45:44 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 13:45:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:45:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:45:44 INFO - Using real prefix '/tools/python27' 13:45:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:45:45 INFO - Installing distribute.............................................................................................................................................................................................done. 13:45:48 INFO - Installing pip.................done. 13:45:48 INFO - Return code: 0 13:45:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 13:45:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 13:45:48 INFO - Reading from file tmpfile_stdout 13:45:48 INFO - Output received: 13:45:48 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) 13:45:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:45:48 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')]} 13:45:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:45:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:45:48 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')]} 13:45:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:45:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:45:48 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 0x105c19490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b8f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffda2471070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a2dd30>, '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 0x105893db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a25b20>, '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.bplbtlSfjJ/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.fgfEXIOfNl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 13:45:48 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 13:45:48 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 13:45:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bplbtlSfjJ/Render', 13:45:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:45:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:45:48 INFO - 'HOME': '/Users/cltbld', 13:45:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:45:48 INFO - 'LOGNAME': 'cltbld', 13:45:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:45:48 INFO - 'MOZ_NO_REMOTE': '1', 13:45:48 INFO - 'NO_EM_RESTART': '1', 13:45:48 INFO - 'PAGER': '/bin/cat', 13:45:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:45:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:45:48 INFO - 'PWD': '/builds/slave/test', 13:45:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:45:48 INFO - 'SHELL': '/bin/bash', 13:45:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fgfEXIOfNl/Listeners', 13:45:48 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 13:45:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:45:48 INFO - 'USER': 'cltbld', 13:45:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:45:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:45:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:45:48 INFO - 'XPC_FLAGS': '0x0', 13:45:48 INFO - 'XPC_SERVICE_NAME': '0', 13:45:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:45:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:45:48 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 13:45:48 INFO - Cleaning up... 13:45:48 INFO - Return code: 0 13:45:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:45:48 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')]} 13:45:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:45:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:45:48 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')]} 13:45:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:45:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:45:48 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 0x105c19490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b8f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffda2471070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a2dd30>, '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 0x105893db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a25b20>, '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.bplbtlSfjJ/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.fgfEXIOfNl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 13:45:48 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 13:45:48 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 13:45:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bplbtlSfjJ/Render', 13:45:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:45:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:45:48 INFO - 'HOME': '/Users/cltbld', 13:45:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:45:48 INFO - 'LOGNAME': 'cltbld', 13:45:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:45:48 INFO - 'MOZ_NO_REMOTE': '1', 13:45:48 INFO - 'NO_EM_RESTART': '1', 13:45:48 INFO - 'PAGER': '/bin/cat', 13:45:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:45:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:45:48 INFO - 'PWD': '/builds/slave/test', 13:45:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:45:48 INFO - 'SHELL': '/bin/bash', 13:45:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fgfEXIOfNl/Listeners', 13:45:48 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 13:45:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:45:48 INFO - 'USER': 'cltbld', 13:45:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:45:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:45:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:45:48 INFO - 'XPC_FLAGS': '0x0', 13:45:48 INFO - 'XPC_SERVICE_NAME': '0', 13:45:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:45:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:45:49 INFO - Downloading/unpacking psutil>=3.1.1 13:45:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:45:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:45:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:45:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:45:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:45:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:45:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:45:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:45:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:45:52 INFO - Installing collected packages: psutil 13:45:52 INFO - Running setup.py install for psutil 13:45:52 INFO - building 'psutil._psutil_osx' extension 13:45:52 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 13:45:53 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 13:45:53 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 13:45:53 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 13:45:53 INFO - building 'psutil._psutil_posix' extension 13:45:53 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 13:45:53 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:45:53 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:45:53 INFO - ^ 13:45:53 INFO - 1 warning generated. 13:45:53 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 13:45:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:45:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:45:53 INFO - Successfully installed psutil 13:45:53 INFO - Cleaning up... 13:45:53 INFO - Return code: 0 13:45:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:45:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:45:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:45:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:45:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:45:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:45:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:45:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105c19490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b8f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffda2471070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a2dd30>, '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 0x105893db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a25b20>, '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.bplbtlSfjJ/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.fgfEXIOfNl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 13:45:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 13:45:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 13:45:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bplbtlSfjJ/Render', 13:45:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:45:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:45:53 INFO - 'HOME': '/Users/cltbld', 13:45:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:45:53 INFO - 'LOGNAME': 'cltbld', 13:45:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:45:53 INFO - 'MOZ_NO_REMOTE': '1', 13:45:53 INFO - 'NO_EM_RESTART': '1', 13:45:53 INFO - 'PAGER': '/bin/cat', 13:45:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:45:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:45:53 INFO - 'PWD': '/builds/slave/test', 13:45:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:45:53 INFO - 'SHELL': '/bin/bash', 13:45:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fgfEXIOfNl/Listeners', 13:45:53 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 13:45:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:45:53 INFO - 'USER': 'cltbld', 13:45:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:45:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:45:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:45:53 INFO - 'XPC_FLAGS': '0x0', 13:45:53 INFO - 'XPC_SERVICE_NAME': '0', 13:45:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:45:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:45:53 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:45:53 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 13:45:53 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 13:45:53 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 13:45:53 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 13:45:53 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 13:45:53 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 13:45:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:45:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:45:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:45:56 INFO - Installing collected packages: mozsystemmonitor 13:45:56 INFO - Running setup.py install for mozsystemmonitor 13:45:56 INFO - Successfully installed mozsystemmonitor 13:45:56 INFO - Cleaning up... 13:45:56 INFO - Return code: 0 13:45:56 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:45:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:45:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:45:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:45:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:45:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:45:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:45:56 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 0x105c19490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b8f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffda2471070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a2dd30>, '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 0x105893db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a25b20>, '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.bplbtlSfjJ/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.fgfEXIOfNl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 13:45:56 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 13:45:56 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 13:45:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bplbtlSfjJ/Render', 13:45:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:45:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:45:56 INFO - 'HOME': '/Users/cltbld', 13:45:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:45:56 INFO - 'LOGNAME': 'cltbld', 13:45:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:45:56 INFO - 'MOZ_NO_REMOTE': '1', 13:45:56 INFO - 'NO_EM_RESTART': '1', 13:45:56 INFO - 'PAGER': '/bin/cat', 13:45:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:45:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:45:56 INFO - 'PWD': '/builds/slave/test', 13:45:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:45:56 INFO - 'SHELL': '/bin/bash', 13:45:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fgfEXIOfNl/Listeners', 13:45:56 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 13:45:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:45:56 INFO - 'USER': 'cltbld', 13:45:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:45:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:45:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:45:56 INFO - 'XPC_FLAGS': '0x0', 13:45:56 INFO - 'XPC_SERVICE_NAME': '0', 13:45:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:45:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:45:56 INFO - Downloading/unpacking jsonschema==2.5.1 13:45:56 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 13:45:56 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 13:45:56 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 13:45:56 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 13:45:56 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 13:45:56 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 13:45:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:45:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:45:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:45:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:45:59 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 13:45:59 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 13:45:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:45:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:45:59 INFO - Downloading functools32-3.2.3-2.tar.gz 13:45:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:46:00 INFO - warning: no files found matching '*.txt' 13:46:00 INFO - no previously-included directories found matching 'build' 13:46:00 INFO - no previously-included directories found matching 'dist' 13:46:00 INFO - no previously-included directories found matching '.git*' 13:46:00 INFO - Installing collected packages: jsonschema, functools32 13:46:00 INFO - Running setup.py install for functools32 13:46:00 INFO - warning: no files found matching '*.txt' 13:46:00 INFO - no previously-included directories found matching 'build' 13:46:00 INFO - no previously-included directories found matching 'dist' 13:46:00 INFO - no previously-included directories found matching '.git*' 13:46:00 INFO - Successfully installed jsonschema functools32 13:46:00 INFO - Cleaning up... 13:46:00 INFO - Return code: 0 13:46:00 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:46:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:46:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:46:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:46:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:46:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:46:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:46:00 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 0x105c19490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b8f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffda2471070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a2dd30>, '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 0x105893db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a25b20>, '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.bplbtlSfjJ/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.fgfEXIOfNl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 13:46:00 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 13:46:00 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 13:46:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bplbtlSfjJ/Render', 13:46:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:46:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:46:00 INFO - 'HOME': '/Users/cltbld', 13:46:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:46:00 INFO - 'LOGNAME': 'cltbld', 13:46:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:46:00 INFO - 'MOZ_NO_REMOTE': '1', 13:46:00 INFO - 'NO_EM_RESTART': '1', 13:46:00 INFO - 'PAGER': '/bin/cat', 13:46:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:46:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:46:00 INFO - 'PWD': '/builds/slave/test', 13:46:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:46:00 INFO - 'SHELL': '/bin/bash', 13:46:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fgfEXIOfNl/Listeners', 13:46:00 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 13:46:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:46:00 INFO - 'USER': 'cltbld', 13:46:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:46:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:46:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:46:00 INFO - 'XPC_FLAGS': '0x0', 13:46:00 INFO - 'XPC_SERVICE_NAME': '0', 13:46:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:46:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:46:00 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:46:00 INFO - Cleaning up... 13:46:00 INFO - Return code: 0 13:46:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:46:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:46:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:46:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:46:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:46:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:46:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:46:00 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 0x105c19490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b8f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffda2471070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a2dd30>, '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 0x105893db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a25b20>, '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.bplbtlSfjJ/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.fgfEXIOfNl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 13:46:00 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 13:46:00 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 13:46:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bplbtlSfjJ/Render', 13:46:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:46:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:46:00 INFO - 'HOME': '/Users/cltbld', 13:46:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:46:00 INFO - 'LOGNAME': 'cltbld', 13:46:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:46:00 INFO - 'MOZ_NO_REMOTE': '1', 13:46:00 INFO - 'NO_EM_RESTART': '1', 13:46:00 INFO - 'PAGER': '/bin/cat', 13:46:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:46:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:46:00 INFO - 'PWD': '/builds/slave/test', 13:46:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:46:00 INFO - 'SHELL': '/bin/bash', 13:46:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fgfEXIOfNl/Listeners', 13:46:00 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 13:46:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:46:00 INFO - 'USER': 'cltbld', 13:46:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:46:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:46:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:46:00 INFO - 'XPC_FLAGS': '0x0', 13:46:00 INFO - 'XPC_SERVICE_NAME': '0', 13:46:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:46:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:46:00 INFO - Downloading/unpacking blobuploader==1.2.4 13:46:00 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 13:46:00 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 13:46:00 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 13:46:00 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 13:46:00 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 13:46:00 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 13:46:03 INFO - Downloading blobuploader-1.2.4.tar.gz 13:46:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:46:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:46:03 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 13:46:03 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 13:46:03 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 13:46:03 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 13:46:03 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 13:46:03 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 13:46:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:46:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:46:04 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 13:46:04 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 13:46:04 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 13:46:04 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 13:46:04 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 13:46:04 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 13:46:04 INFO - Downloading docopt-0.6.1.tar.gz 13:46:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:46:04 INFO - Installing collected packages: blobuploader, requests, docopt 13:46:04 INFO - Running setup.py install for blobuploader 13:46:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:46:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:46:04 INFO - Running setup.py install for requests 13:46:05 INFO - Running setup.py install for docopt 13:46:05 INFO - Successfully installed blobuploader requests docopt 13:46:05 INFO - Cleaning up... 13:46:05 INFO - Return code: 0 13:46:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:46:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:46:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:46:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:46:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:46:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:46:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:46:05 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 0x105c19490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b8f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffda2471070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a2dd30>, '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 0x105893db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a25b20>, '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.bplbtlSfjJ/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.fgfEXIOfNl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 13:46:05 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 13:46:05 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 13:46:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bplbtlSfjJ/Render', 13:46:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:46:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:46:05 INFO - 'HOME': '/Users/cltbld', 13:46:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:46:05 INFO - 'LOGNAME': 'cltbld', 13:46:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:46:05 INFO - 'MOZ_NO_REMOTE': '1', 13:46:05 INFO - 'NO_EM_RESTART': '1', 13:46:05 INFO - 'PAGER': '/bin/cat', 13:46:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:46:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:46:05 INFO - 'PWD': '/builds/slave/test', 13:46:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:46:05 INFO - 'SHELL': '/bin/bash', 13:46:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fgfEXIOfNl/Listeners', 13:46:05 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 13:46:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:46:05 INFO - 'USER': 'cltbld', 13:46:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:46:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:46:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:46:05 INFO - 'XPC_FLAGS': '0x0', 13:46:05 INFO - 'XPC_SERVICE_NAME': '0', 13:46:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:46:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:46:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:46:05 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-7n0era-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:46:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:46:05 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-e6kUXJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:46:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:46:06 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-DPnB82-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:46:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:46:06 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-xEQRcS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:46:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:46:06 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-CzTVEJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:46:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:46:06 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-QnLLnc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:46:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:46:06 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-DoQ0OC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:46:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:46:06 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-eWvuNj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:46:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:46:06 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-TAOY25-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:46:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:46:06 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-DF2Snx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:46:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:46:06 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-5xo5qK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:46:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:46:07 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-o1DB7B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:46:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:46:07 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-zeiZL7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:46:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:46:07 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-SSl_6y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:46:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:46:07 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-CaLpSl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:46:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:46:07 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-tSdUSn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:46:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:46:07 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-t0DMXB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:46:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:46:07 INFO - Running setup.py install for manifestparser 13:46:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:46:07 INFO - Running setup.py install for mozcrash 13:46:07 INFO - Running setup.py install for mozdebug 13:46:08 INFO - Running setup.py install for mozdevice 13:46:08 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:46:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:46:08 INFO - Running setup.py install for mozfile 13:46:08 INFO - Running setup.py install for mozhttpd 13:46:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:46:08 INFO - Running setup.py install for mozinfo 13:46:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:46:08 INFO - Running setup.py install for mozInstall 13:46:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:46:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:46:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:46:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:46:08 INFO - Running setup.py install for mozleak 13:46:08 INFO - Running setup.py install for mozlog 13:46:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:46:09 INFO - Running setup.py install for moznetwork 13:46:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:46:09 INFO - Running setup.py install for mozprocess 13:46:09 INFO - Running setup.py install for mozprofile 13:46:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:46:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:46:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:46:09 INFO - Running setup.py install for mozrunner 13:46:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:46:09 INFO - Running setup.py install for mozscreenshot 13:46:09 INFO - Running setup.py install for moztest 13:46:10 INFO - Running setup.py install for mozversion 13:46:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:46:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:46:10 INFO - Cleaning up... 13:46:10 INFO - Return code: 0 13:46:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:46: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')]} 13:46:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:46:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:46: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')]} 13:46:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:46:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:46:10 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 0x105c19490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b8f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffda2471070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a2dd30>, '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 0x105893db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a25b20>, '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.bplbtlSfjJ/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.fgfEXIOfNl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 13:46:10 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 13:46:10 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 13:46:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bplbtlSfjJ/Render', 13:46:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:46:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:46:10 INFO - 'HOME': '/Users/cltbld', 13:46:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:46:10 INFO - 'LOGNAME': 'cltbld', 13:46:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:46:10 INFO - 'MOZ_NO_REMOTE': '1', 13:46:10 INFO - 'NO_EM_RESTART': '1', 13:46:10 INFO - 'PAGER': '/bin/cat', 13:46:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:46:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:46:10 INFO - 'PWD': '/builds/slave/test', 13:46:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:46:10 INFO - 'SHELL': '/bin/bash', 13:46:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fgfEXIOfNl/Listeners', 13:46:10 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 13:46:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:46:10 INFO - 'USER': 'cltbld', 13:46:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:46:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:46:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:46:10 INFO - 'XPC_FLAGS': '0x0', 13:46:10 INFO - 'XPC_SERVICE_NAME': '0', 13:46:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:46:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:46:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:46:10 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-DkCfIt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:46:10 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)) 13:46:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:46:10 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-ePFWRx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:46:10 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)) 13:46:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:46:10 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-_IiRCL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:46:10 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)) 13:46:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:46:10 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-CjkDlU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:46:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:46:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:46:10 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-YtdRpc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:46:11 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)) 13:46:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:46:11 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-neeS_M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:46:11 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)) 13:46:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:46:11 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-52Xt9W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:46:11 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)) 13:46:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:46:11 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-vxAbOb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:46:11 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)) 13:46:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:46:11 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-DJY_0v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:46:11 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)) 13:46:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:46:11 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-9SOmYq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:46:11 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)) 13:46:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:46:11 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-N1bVlT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:46:11 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)) 13:46:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:46:11 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-7LCbjS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:46:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 13:46:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:46:11 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-sri6wu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:46:11 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)) 13:46:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:46:11 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-DcmKiH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:46:12 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)) 13:46:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:46:12 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-Fimp1m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:46:12 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)) 13:46:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:46:12 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-D6FCSm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:46:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 13:46:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:46:12 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-fk1M_9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:46:12 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)) 13:46:12 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)) 13:46:12 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)) 13:46:12 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)) 13:46:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:46:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:46:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:46:12 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 13:46:12 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 13:46:12 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 13:46:12 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 13:46:12 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 13:46:12 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 13:46:14 INFO - Downloading blessings-1.6.tar.gz 13:46:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:46:14 INFO - Installing collected packages: blessings 13:46:14 INFO - Running setup.py install for blessings 13:46:15 INFO - Successfully installed blessings 13:46:15 INFO - Cleaning up... 13:46:15 INFO - Return code: 0 13:46:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:46:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:46:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:46:15 INFO - Reading from file tmpfile_stdout 13:46:15 INFO - Current package versions: 13:46:15 INFO - blessings == 1.6 13:46:15 INFO - blobuploader == 1.2.4 13:46:15 INFO - docopt == 0.6.1 13:46:15 INFO - functools32 == 3.2.3-2 13:46:15 INFO - jsonschema == 2.5.1 13:46:15 INFO - manifestparser == 1.1 13:46:15 INFO - mozInstall == 1.12 13:46:15 INFO - mozcrash == 1.0 13:46:15 INFO - mozdebug == 0.1 13:46:15 INFO - mozdevice == 0.48 13:46:15 INFO - mozfile == 1.2 13:46:15 INFO - mozhttpd == 0.7 13:46:15 INFO - mozinfo == 0.9 13:46:15 INFO - mozleak == 0.1 13:46:15 INFO - mozlog == 3.4 13:46:15 INFO - moznetwork == 0.27 13:46:15 INFO - mozprocess == 0.23 13:46:15 INFO - mozprofile == 0.28 13:46:15 INFO - mozrunner == 6.13 13:46:15 INFO - mozscreenshot == 0.1 13:46:15 INFO - mozsystemmonitor == 0.3 13:46:15 INFO - moztest == 0.7 13:46:15 INFO - mozversion == 1.4 13:46:15 INFO - psutil == 3.1.1 13:46:15 INFO - requests == 1.2.3 13:46:15 INFO - wsgiref == 0.1.2 13:46:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:46:15 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')]} 13:46:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:46:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:46: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')]} 13:46:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:46:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:46:16 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 0x105c19490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b8f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffda2471070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a2dd30>, '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 0x105893db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a25b20>, '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.bplbtlSfjJ/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.fgfEXIOfNl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 13:46:16 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 13:46:16 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 13:46:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bplbtlSfjJ/Render', 13:46:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:46:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:46:16 INFO - 'HOME': '/Users/cltbld', 13:46:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:46:16 INFO - 'LOGNAME': 'cltbld', 13:46:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:46:16 INFO - 'MOZ_NO_REMOTE': '1', 13:46:16 INFO - 'NO_EM_RESTART': '1', 13:46:16 INFO - 'PAGER': '/bin/cat', 13:46:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:46:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:46:16 INFO - 'PWD': '/builds/slave/test', 13:46:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:46:16 INFO - 'SHELL': '/bin/bash', 13:46:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fgfEXIOfNl/Listeners', 13:46:16 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 13:46:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:46:16 INFO - 'USER': 'cltbld', 13:46:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:46:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:46:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:46:16 INFO - 'XPC_FLAGS': '0x0', 13:46:16 INFO - 'XPC_SERVICE_NAME': '0', 13:46:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:46:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:46:16 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)) 13:46:16 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)) 13:46:16 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)) 13:46:16 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)) 13:46:16 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)) 13:46:16 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)) 13:46:16 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)) 13:46:16 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)) 13:46:16 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)) 13:46:16 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)) 13:46:16 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)) 13:46:16 INFO - Cleaning up... 13:46:16 INFO - Return code: 0 13:46:16 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:46: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')]} 13:46:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:46:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:46: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')]} 13:46:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:46:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:46:17 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 0x105c19490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b8f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffda2471070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a2dd30>, '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 0x105893db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a25b20>, '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.bplbtlSfjJ/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.fgfEXIOfNl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 13:46:17 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 13:46:17 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 13:46:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bplbtlSfjJ/Render', 13:46:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:46:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:46:17 INFO - 'HOME': '/Users/cltbld', 13:46:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:46:17 INFO - 'LOGNAME': 'cltbld', 13:46:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:46:17 INFO - 'MOZ_NO_REMOTE': '1', 13:46:17 INFO - 'NO_EM_RESTART': '1', 13:46:17 INFO - 'PAGER': '/bin/cat', 13:46:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:46:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:46:17 INFO - 'PWD': '/builds/slave/test', 13:46:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:46:17 INFO - 'SHELL': '/bin/bash', 13:46:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fgfEXIOfNl/Listeners', 13:46:17 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 13:46:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:46:17 INFO - 'USER': 'cltbld', 13:46:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:46:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:46:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:46:17 INFO - 'XPC_FLAGS': '0x0', 13:46:17 INFO - 'XPC_SERVICE_NAME': '0', 13:46:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:46:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:46:17 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:46:17 INFO - Cleaning up... 13:46:17 INFO - Return code: 0 13:46:17 INFO - Running post-action listener: _resource_record_post_action 13:46:17 INFO - Running post-action listener: _start_resource_monitoring 13:46:17 INFO - Starting resource monitoring. 13:46:17 INFO - [mozharness: 2016-12-15 21:46:17.393794Z] Finished create-virtualenv step (success) 13:46:17 INFO - [mozharness: 2016-12-15 21:46:17.394780Z] Running install step. 13:46:17 INFO - Running pre-action listener: _resource_record_pre_action 13:46:17 INFO - Running main action method: install 13:46:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:46:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:46:17 INFO - Reading from file tmpfile_stdout 13:46:17 INFO - Detecting whether we're running mozinstall >=1.0... 13:46:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:46:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:46:17 INFO - Reading from file tmpfile_stdout 13:46:17 INFO - Output received: 13:46:17 INFO - Usage: mozinstall [options] installer 13:46:17 INFO - Options: 13:46:17 INFO - -h, --help show this help message and exit 13:46:17 INFO - -d DEST, --destination=DEST 13:46:17 INFO - Directory to install application into. [default: 13:46:17 INFO - "/builds/slave/test"] 13:46:17 INFO - --app=APP Application being installed. [default: firefox] 13:46:17 INFO - mkdir: /builds/slave/test/build/application 13:46:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 13:46:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 13:46:48 INFO - Reading from file tmpfile_stdout 13:46:48 INFO - Output received: 13:46:48 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 13:46:48 INFO - Running post-action listener: _resource_record_post_action 13:46:48 INFO - [mozharness: 2016-12-15 21:46:48.836145Z] Finished install step (success) 13:46:48 INFO - [mozharness: 2016-12-15 21:46:48.836330Z] Running run-tests step. 13:46:48 INFO - Running pre-action listener: _resource_record_pre_action 13:46:48 INFO - Running main action method: run_tests 13:46:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:46:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:46:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:46:48 INFO - 2016-12-15 13:46:48.851 screenresolution[1902:11996] starting screenresolution argv=screenresolution get 13:46:48 INFO - 2016-12-15 13:46:48.853 screenresolution[1902:11996] Display 0: 1600x1200x32@60 13:46:48 INFO - 2016-12-15 13:46:48.859 screenresolution[1903:11999] starting screenresolution argv=screenresolution list 13:46:48 INFO - Available Modes on Display 0 13:46:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:46:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:46:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:46:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:46:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:46:49 INFO - Intel Iris: 13:46:49 INFO - Chipset Model: Intel Iris 13:46:49 INFO - Type: GPU 13:46:49 INFO - Bus: Built-In 13:46:49 INFO - VRAM (Dynamic, Max): 1536 MB 13:46:49 INFO - Vendor: Intel (0x8086) 13:46:49 INFO - Device ID: 0x0a2e 13:46:49 INFO - Revision ID: 0x0009 13:46:49 INFO - Displays: 13:46:49 INFO - Display: 13:46:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:46:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:46:49 INFO - Display Serial Number: 1600x1200 60 13:46:49 INFO - Main Display: Yes 13:46:49 INFO - Mirror: Off 13:46:49 INFO - Online: Yes 13:46:49 INFO - Rotation: Supported 13:46:49 INFO - Return code: 0 13:46:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:46:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:46:49 INFO - Python 2.7.3 13:46:49 INFO - Return code: 0 13:46:49 INFO - grabbing minidump binary from tooltool 13:46:49 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')]} 13:46:49 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 0x105a2dd30>, '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 0x105893db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a25b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:46:49 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 13:46:49 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 13:46:49 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 13:46:49 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:46:49 INFO - Return code: 0 13:46:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:46:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:46:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:46:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:46:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:46:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/M5CbLo8uSS-RC6QtkKztxw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0070', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 13:46:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/M5CbLo8uSS-RC6QtkKztxw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0070 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 13:46:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bplbtlSfjJ/Render', 13:46:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:46:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:46:49 INFO - 'HOME': '/Users/cltbld', 13:46:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:46:49 INFO - 'LOGNAME': 'cltbld', 13:46:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:46:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:46:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:46:49 INFO - 'MOZ_NO_REMOTE': '1', 13:46:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:46:49 INFO - 'NO_EM_RESTART': '1', 13:46:49 INFO - 'PAGER': '/bin/cat', 13:46:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:46:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:46:49 INFO - 'PWD': '/builds/slave/test', 13:46:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:46:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:46:49 INFO - 'SHELL': '/bin/bash', 13:46:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fgfEXIOfNl/Listeners', 13:46:49 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 13:46:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:46:49 INFO - 'USER': 'cltbld', 13:46:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:46:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:46:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:46:49 INFO - 'XPC_FLAGS': '0x0', 13:46:49 INFO - 'XPC_SERVICE_NAME': '0', 13:46:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:46:49 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/M5CbLo8uSS-RC6QtkKztxw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0070', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 13:46:49 INFO - mozversion application_buildid: 20161215114101 13:46:49 INFO - mozversion application_changeset: f3353c8bb6f3de41df3e889da27115d6e29a7e93 13:46:49 INFO - mozversion application_display_name: FirefoxDeveloperEdition 13:46:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:46:49 INFO - mozversion application_name: Firefox 13:46:49 INFO - mozversion application_remotingname: firefox-dev 13:46:49 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 13:46:49 INFO - mozversion application_vendor: Mozilla 13:46:49 INFO - mozversion application_version: 52.0a2 13:46:49 INFO - mozversion platform_buildid: 20161215114101 13:46:49 INFO - mozversion platform_changeset: f3353c8bb6f3de41df3e889da27115d6e29a7e93 13:46:49 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 13:46:49 INFO - mozversion platform_version: 52.0a2 13:46:49 INFO - using testdate: 1481838409 13:46:49 INFO - actual date: 1481838409 13:46:49 INFO - starting webserver on 'localhost:51293' 13:46:49 INFO - SUITE-START | Running 2 tests 13:46:49 INFO - TEST-START | dromaeo_css 13:46:49 INFO - operating with platform_type : mac_ 13:46:49 INFO - Initialising browser for dromaeo_css test... 13:46:49 INFO - TEST-INFO | started process 1913 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/tmpjzn4Hg/profile http://localhost:51293/getInfo.html) 13:46:52 INFO - PROCESS | 1913 | __metrics Screen width/height:1600/1200 13:46:52 INFO - PROCESS | 1913 | colorDepth:24 13:46:52 INFO - PROCESS | 1913 | Browser inner width/height: 1024/689 13:46:52 INFO - PROCESS | 1913 | __metrics 13:46:52 INFO - PROCESS | 1913 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 13:46:52 INFO - PROCESS | 1913 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:46:53 INFO - TEST-INFO | 1913: exit 0 13:46:53 INFO - Browser initialized. 13:46:53 INFO - Running cycle 1/1 for dromaeo_css test... 13:46:53 INFO - TEST-INFO | started process 1915 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/tmpjzn4Hg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:47:05 INFO - PROCESS | 1915 | RSS: Main: 238628864 13:47:05 INFO - PROCESS | 1915 | 13:47:56 INFO - PROCESS | 1915 | 1481838476380 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 13:50:11 INFO - PROCESS | 1915 | Cycle 1(1): loaded http://localhost:51293/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51293/tests/dromaeo/cssquery-ext.html) 13:50:13 INFO - PROCESS | 1915 | RSS: Main: 418648064 13:50:13 INFO - PROCESS | 1915 | 13:53:16 INFO - PROCESS | 1915 | Cycle 1(1): loaded http://localhost:51293/tests/dromaeo/cssquery-ext.html (next: http://localhost:51293/tests/dromaeo/cssquery-jquery.html) 13:53:17 INFO - PROCESS | 1915 | RSS: Main: 361033728 13:53:17 INFO - PROCESS | 1915 | 13:56:19 INFO - PROCESS | 1915 | Cycle 1(1): loaded http://localhost:51293/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51293/tests/dromaeo/cssquery-mootools.html) 13:56:20 INFO - PROCESS | 1915 | RSS: Main: 339243008 13:56:20 INFO - PROCESS | 1915 | 13:59:22 INFO - PROCESS | 1915 | Cycle 1(1): loaded http://localhost:51293/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51293/tests/dromaeo/cssquery-prototype.html) 13:59:23 INFO - PROCESS | 1915 | RSS: Main: 338509824 13:59:23 INFO - PROCESS | 1915 | 14:02:26 INFO - PROCESS | 1915 | Cycle 1(1): loaded http://localhost:51293/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51293/tests/dromaeo/cssquery-yui.html) 14:02:27 INFO - PROCESS | 1915 | RSS: Main: 325730304 14:02:27 INFO - PROCESS | 1915 | 14:05:30 INFO - PROCESS | 1915 | Cycle 1(1): loaded http://localhost:51293/tests/dromaeo/cssquery-yui.html (next: http://localhost:51293/tests/dromaeo/cssquery-dojo.html) 14:05:30 INFO - PROCESS | 1915 | RSS: Main: 506982400 14:05:30 INFO - PROCESS | 1915 | 14:05:30 INFO - PROCESS | 1915 | __start_tp_report 14:05:30 INFO - PROCESS | 1915 | _x_x_mozilla_page_load 14:05:30 INFO - PROCESS | 1915 | _x_x_mozilla_page_load_details 14:05:30 INFO - PROCESS | 1915 | |i|pagename|runs| 14:05:30 INFO - PROCESS | 1915 | |0;dojo.html;3301.8183999880002;3309.626505000425;3318.1372843060813;3335.316260166666;3366.1921138926664;5683.431656834319;5760.855978600537;5773.249477567911;5775.826725198241;5820.563457740675;5674.943250567503;5778.855528611787;5782.277215348082;5815.941840581603;5820.214271073414;5177.042247184283;5223.981323641891;5233.58131349493;5247.973760131194;5286.656367336111;5182.922256166161;5191.896162076756;5269.6311258211945;5307.548858347045;5312.893742125155;5122.462141475147;5228.5555727763185;5231.136862417697;5261.421243663194;5273.8945221095555;4556.3621093047;5260.579153667697;5273.4462881397285;5275.762590683428;5284.260203571523;5112.897742045138;5213.348331458567;5223.242629818655;5223.869403264911;5262.7631756571045;5797.768089276424;5815.709214539256;5815.88368232633;5820.214271073393;5827.184194212814;6056.182415373956;6097.024476083805;6098;6098.14625952367;6100.267967843843;6147.524594097429;6155.815325540196;6158.507319414481;6158.8768224635705;6215.502759779207;5726.627769194989;5730.885382292373;5773.509251713609;5779.595428320019;5799.797007104731;6283.96858015707;6284.654344011124;6285.371462853724;6306.49548036156;6313.273973493016;5983.1623572699855;6035.245594300713;6044.274687037584;6045.667488288098;6083.726232319556;6109.34393638171;6152.046432802923;6235.189425374672;6239.844003899848;6253.781117660951;6211.596246243979;6243.7814676486105;6246.312905580383;6249.625022498573;6276.46650034743;6021.939780602138;6083.39166083397;6095.207623008981;6256.593321434092;6285.182926219653;4839.879003024953;4889.437714662859;4904.656674032854;4928.112939670822;4940.580050695659;4388.29787234041;4402.449693788277;4413.271810151353;4419.071994881111;4436.44544431946;5580.553555715533;5785.797497087582;5798.9130163047585;5826.096954971986;5838.854028649318;6185.8144255672405;6189.319174890758;6231.968840155861;6261.373862613702;6263.592866463665;5791.652500849878;5794.159846822187;5807.186993820868;5818.389069147764;5839.357670656224;4773.71357718538;5342.070301964816;5362.946370536322;5380.219868119101;5393.056215162331;6144.818589400698;6256.562040657111;6257.624542527463;6286.968565157145;6310;6193;6248.875022499616;6262.874742505215;6297.653629050263;6316.968415157895;6280.089387038763;6281.9948808190475;6288.339724329063;6289.528285378524;6301.936980630135;4588.701734387254;6072.666003369857;6204.875902482016;6212.06818977157;6237.75048998035;6153.230846144232;6175.289841668266;6187.783427580013;6208.72060757258;6213.440790328892;5983.970080149571;6107.053406721965;6119.4798442132915;6119.541034422347;6119.571629985859;5978.372270911492;6100.572959892942;6114.755409783559;6118.5411094169;6142.508599311955;4880.243562247857;5779.306483221872;5844.181814545882;5874.206982057539;5964.7912323068485;6083.117947897442;6116.701002199415;6119.531596328132;6122.184701511036;6123.343628574382;5765.625234359753;5821.38875418075;5836.562257830765;5878.147668587945;5925.496332811759 14:05:30 INFO - PROCESS | 1915 | |1;ext.html;476.7258826174948;480.1812908990172;490.95916656687825;503.4763845600575;522.4984015345268;23599.292021239347;37961.62038379615;39022.60977390225;39140.80429597848;40598.98502537438;39607.801960990226;39863;40022.39966400506;40079.59920400795;40099;9185.805845240126;9277.95361023194;9342.065793420637;9347.345685801996;9378.718638440858;9224;9319.534023298842;9327.62689492423;9333.626654933794;9339.906600934006;9279.025702301262;9319.580618872167;9324.440533567973;9336.299777516679;9346;9247.39891907024;9337.393069450532;9342.112499312554;9349.39228950123;9367.672131475434;9306.72079837606;9360.672376466859;9363.578638961262;9382.765430864214;9396.107369799858;37962.62037379646;38303.42544861829;38319.42520862189;38327;38455;3182.427163110639;3219.3400352927592;3229.128135403428;3234.56333394991;3297.868085276586;28134.718652813415;28330.716692833015;29346.70653293461;29348.559771603443;29799;2660.2950218192195;3109.8134111953127;3156.925679878575;3195.2970346523725;3236.8381580921096;345467;346829;347330;347582.9999999975;349576;54345;54654.18018729722;54711.45288547143;54723;54778.72610636921;29850.402991940475;33022.3395532088;33629.654813807174;35202.823985879906;56996.430035699945;15465.226738663247;15644.843551564567;15662.216889155497;15671.294791734399;15675.137867417401;11984.520619175135;12068.396580170956;12105.33420661872;12109.03127749778;12117.697057573454;18331.908340458478;18336.991465469262;18348.908255458904;18366.632667346577;18391.172397242157;7322.743703970335;7466.813329666693;7468.738594149178;7478.551286922802;7489.662965166581;820.4175035724583;881.4402854187637;921.3504479342048;936.9765755856158;1091.7434402915308;28040.71959280422;28354.432911341657;28924.276893077422;29174.124776256747;29197.416051678847;949.4350861237691;1023.1507848485887;1037.994810025945;1084.1976937066513;1127.5116845763678;14385.568432947028;14799.556013319618;15041.623959400884;15144.091354518765;15244.542663720105;611164;618063;619657.9017104886;619797;621525;53063.40809775713;53576.464235357926;53704.46295537073;53756;53882.19176712353;1356.4099616666583;1374.5326588959801;1387.3964825301027;1390.9582712518843;1395.5534229046607;28064.71935280621;28568.714312857435;28701.28246793763;29084.854575726986;29256.26859328534;46444.7677761609;51195.74402127966;53010.20484692656;53019;56608;594877;600974;601927;602543;603143;39434.605653943094;39785;40211.39682904817;40401;40488.59511404848;43421;43617.563824362616;43889.561104389824;44096.67709968706;44148.55851441444;36425.635743642226;36464;37289.81355093207;38289.425658614586;38431.61568384392;36632.633673662924;36654.42895660992;36818;37101;37522.624773751915;30981.845090775303;31786.36427271488;31924.201894952814;34851.65148348484;35228.295434091684;28856.567151492323;32484.83757581197;33474.995750127535;34665;34694.13264668403;24507.632385513876;24791.752082479667;24883.626745598467;25352.492950141263;25529.361765956 14:05:30 INFO - PROCESS | 1915 | |2;jquery.html;347.04735501049623;364.50791431567404;368.3278017617847;376.29632587062196;393.87396033269346;14516;14841.480548180816;15553.300101495432;19214.807851921476;19819.504512387197;15979.961302515328;18827.43517694468;18917.527061823428;20047.899760501175;20056;5665.150227465892;5996.670183139926;6006.369331220225;6011.489023433013;6013.098035294697;5614.3543492498475;5823;5859.6484210947265;5886.352501224862;5891.175235467038;5051.166557518005;5777.133429985514;5840.678762668052;5845.620034697731;5859.853503662422;5570.777168913239;5788.073908174714;5859.384764599741;5862.120681897707;5865;4921.458639549646;5124.615653825978;5131.461196574343;5133.204353325241;5145.176771716508;21207.78792212089;24696.876515617485;24931.50136997268;24993.750062499505;25078.749212507824;2339.263132113379;2369.8104151667826;2409.265174121892;2440.280117365364;2840.758535524483;14535.927320363437;14876.404943802236;15082.622934426618;15701.685966280611;15805.051696898108;1749.5538637647446;1782.4652604218682;1827.4700336902283;1858.8884666919894;1930.9517262068562;87791;90675.22518270487;107060.46469767601;107307;108759;10442.321249118782;10715.303505272132;10814.188935829845;10862.565497380174;11000.394978276272;13398.598042058753;13407.530736424178;13470.93264533671;13482.39329230187;13546.661333466545;8340.416170868102;8383.790405239917;8419.07390187079;8465.238128568335;8555.657773688983;7106.857862842714;8364.958175209207;8395.580220988926;8541.829163416696;8601.655933762579;6334.239891213084;6454.677266136674;6466.805995820133;6482.513811464159;6484.351564843572;1395.1026603818798;1736.6526694661118;1772.3087995681694;1773.2463702926204;1779.8665100117544;900.5902314446911;913.9725808225763;930.9394889332171;968.1818863060703;998.8252055890335;17156.227969741143;17809.7328540072;20022.69965950512;21767.455813604785;21826.890865545887;936.4989730494153;952;988.7972965542046;1015.6292953072076;1040.1834559870465;9329.673461428953;9498.90501094994;10756.08573271265;11101.944490277498;11396;572618;578100;582137;598321;598397;107120;113585.4320728391;128977.35511322384;132673;145943;927.5408672707053;943.6932996776076;988.0564061321421;1014.8325526288228;1018.104068419771;15068.397264109752;18228.726569101207;18585;19098.61802763909;19257.32599359016;53149.671258218856;54365;55941.40585941373;56495.71752141213;58150.709246453494;80048.59975700318;82173.58913205395;82836.58581707053;83400;83521.58239208766;7108.502404831612;7820.413468989735;7847.136814950237;7902.893594896604;8399.790005249917;7193.388561972291;10075.546600402853;10531.315464494783;12139.96810271137;12614.36928153607;8495.702650407453;11343.773124537629;11447.599334023269;11468.541258349573;11517.25137866036;11008.889911101107;11078;11323.660290191307;11541.711457213636;11613.825792612948;10528.315659482108;10545.9472702636;10657.57369705203;10669.786604268027;10955;8040.638171282189;8246.134155913544;11727.237729547553;11801.704957376134;12178.817317740419;10942.835857461985;11458.369789661649;11809.350485723024;11917.76164476723;12037.398130093285 14:05:30 INFO - PROCESS | 1915 | |3;mootools.html;1233.1491271022994;1277.11240687722;1289.5486579697101;1311.252586025965;1594.7129516686998;7398.778036658902;7735.187805280449;8114.229148230924;8382.664693412271;8416.200460956215;8125.146859579733;8225.177482251787;8235.91764082359;8247.134050924642;8256.66973321068;2263.5586060718124;2286.862788232703;2290.3243543154726;2296.632538793793;2306.2043594959796;2298.6781850540856;2330.96503552446;2355.3169580821627;2356.2460012795914;2357.6581395697613;2288.4850908545527;2295.1393227539675;2318.814494840417;2319.118734880743;2326.7789559991775;2244.876531790751;2265.161890100665;2293.4037150341032;2295.885205739706;2296.70142881426;1943.4849764812336;2313.051648823991;2327.0924339507606;2333.171724037959;2339.040993192783;8191.79520511992;8241.711540096068;8277.668893244263;8284;8338.207870252316;5009.874753131165;5211;5234.450382709799;5248.8687782805355;5274.4198138204765;3030.3787723516625;3488.66857648523;3555.3600351936643;3590.784552926833;3596.29872174926;1061.5382308695741;1091.6343025086605;1429.5282556756245;1724.5774785177587;1811.3660218923403;10942.890571094347;11003.33979961197;11004.834927476095;11014.284071535325;11024.118070554417;3661.7436779425184;4417.4036505072045;4466.3523789050405;4522.0277640307495;4526.88682782933;8692.174243446863;8698.21716045559;8707;8711.695090671796;8713.825723485495;5124.15451450514;5130.461301563282;5143.588512918957;5152.716600587004;5152.845414637567;4017.357222844331;4274.166537525152;4407.316865885793;4758.262469317192;4812.374391329105;3623;3634.7092232621326;3708.4066549351974;3715.9628403716156;3726.869559565402;1110.0841805510486;1133.7279053027214;1144.7996600594863;1145.4043897173424;1146.4726225935997;834.0700119366862;847.0301504776996;887.5784002598714;895.1809094678297;912.6030176873081;5428.945710542923;5461.315270887169;5616.85957851057;5629.971850140805;5800.187973683687;823.7693445835175;851.2508992087047;857.7769779857282;866.913308669128;896.8475359188953;3598.3522965865873;3771.9245615088093;3882.8446862125766;4059.3910913363234;4067.4102255173425;9488.430694158373;9570.33007689469;10172.593096276214;10317.742056448498;10328.741781455374;10015.949920250352;10255.487225638688;10276.280660353703;10295.794084118275;10313.948430257951;7559.773206803805;7907.9604601976625;7926.048874135141;7940.602969851484;7950.761477155579;5333;5373.758180881791;5380.408155102858;5439.4288599696465;5673.205751194753;9251.444913305224;9326.813463730823;9376.531173441164;9441.811163776823;9496.287778416785;9196.632134714537;9518.095780900803;9577;9661.903380966382;9691.466969316472;7058.25888281723;7086.822829429306;7235.2402997684485;7254.96372518134;7271.490995630255;7336.37640800538;7340.449466290156;7372.189059203591;7392.667329970272;7433.814154645961;6945.617991010543;7027.754028608974;7094.574325540484;7144.821379465555;7197.748078817216;6949.965250173716;6960.965195173992;6998.370146686822;7037.859242815012;7042.366187043191;6560.048792925095;6598.901016484852;6635.303293154149;6658.966705166443;6697.665116744241;4342.305231162999;4602.4937256902385;4813.326134341265;4815.22956326986;4973.701577905337;4297.656187505055;4642.025174713348;4815.15734746425;4921.4832442593015;4996.675216110941 14:05:30 INFO - PROCESS | 1915 | |4;prototype.html;264.72468632622076;300.66926380980925;306.06344585568155;311.3446195757931;314.66959692323053;4302.419173411588;4520.254158063924;5195.142801437763;5236.973815130924;5263.263103186341;3994.480717506727;4880.950595621941;5132;5184.53339199472;5207.453217412174;3161.1981170732693;3236.643969163396;3802.7943792600363;3849.499565056539;3856.46009558662;3310.486874534439;3765.0775559987705;3803.429485577172;3912.4522566840665;3922.195949830278;3561.9492249786376;3599.478075679025;3766.6665001473757;3859.671927886133;3936.468576742132;3402;3405.438102713074;3792.639699228569;3822.73240873139;3905.383393126612;3027.5912751778446;3451.5340429042008;3454.9481757773656;3470.514128022079;3491.930161396783;4357.956420435787;4406.361077643755;5012.223105418666;5033.320501732256;5041.747912604392;4507.616852567503;4518.864434066983;4523.728576285401;4557.58981691646;4578.51925547816;4532.365468834366;4757.548032936866;5248.661591294195;5428.809991650312;5538.9169162462595;3226.370857682753;3390.304987477561;3690.9665293717803;3832.5209348831395;3853.402722578005;4633.721976681376;4964;5583.804566840182;5733.541316694655;5753.395893431171;3258.967410325914;3670.541182352206;3803.4675145479655;3897;3921.235359105004;3324.7173990211104;3377.071305391037;3563.483294922222;3945.8421663133154;3952.703547233969;3558.733095017832;3743.344914639927;3793.639604237593;4181.28216659174;4210.705250632487;4458.687891847604;5282.392524859613;5535.363433205152;5537.916931246034;5545;1138.214631904;1202.705337192376;1204.00669447706;1285.517930775959;1290.3999640167367;771.182546500711;871.0984131423946;877.7893305606736;899.5592159841631;899.7030979776789;4898.681585696918;4901.324006994754;5101.979604079199;5136.614753893473;5281.128613778684;4821.493743156988;5375.650582712189;5630.887382252414;5686.516646085046;5711.942880571224;4292.6995110342605;4848.908995475989;5154.613403994716;5207.479252074762;5225.712585807817;2433.5011322678806;2510.6736124303725;2562.923112306634;2661.7604415602686;2738.4796888591504;5062.645614806928;5458.1266997280245;5489.752961116759;5510.91733623996;5517.917231241535;4461.5092339842595;5060.823845746291;5374.946250537523;5444.564434845203;5817.418258174149;5566.972165139148;5813.360530341659;5859.384764599741;5931.233666621726;5951.018082016505;4707.741074240949;5025.824096156617;5739.770409183753;5745.242283703762;5769.8557536061935;4383.561643835591;4750.382450281441;5055.649123157749;5110.722319420145;5560.471755183251;5051.949480505295;5148.293228875204;5235.3717553893775;5639.802606908738;5814.656572470931;3201.9519707203945;3774.3960966245268;3796.6393192646656;3796.7532110412735;3862.729608927348;4758.476567577494;5391.32608423947;5397.568194544506;5574.996500629763;5683.687397193194;4314.007778211092;5371.475406559067;5441.707766801029;5458.781648734006;5585.301837270341;5389.27244821944;5450.454954504517;5462.569156725219;5480.0133927010675;5507.807226747044;4546.29532422475;4733.5503127202865;5029.220470827027;5353.22378255143;5418.674879507243;5196.480351964773;5293.735313234246;5416.322959630046;5418.42624753239;5491.066518691752;4517.435320584927;4547.909041819211;5471.945280547144;5496.780128794803;5497.917531236954 14:05:30 INFO - PROCESS | 1915 | |5;yui.html;603.226816264267;698.902153698482;707.2465493212716;775.689724110356;780.2977320411633;13638.522651707191;14424.711505769877;14662.853371466283;14678;14701.70596588069;12181.634550963485;12199.207051541644;12288.938555307233;13126.803097953538;13365.131266467673;666.0874601795547;814.132948409943;824.9793755156109;825.7729124490753;829.4028299624291;680.9625470599115;796.6574373019599;827.1356432527992;831.3183189784379;833.9291160251386;872.995635021824;873.1574031060026;873.401719821924;873.7859224489977;874.422880898607;693.04013940692;867.8828358171629;870.1429092344036;870.2602787630495;871.8866547348836;528.2973645052072;621.2327775197627;641.1376698340724;645.2740666749906;646.977355792545;14034.017618766691;14063;14242.07426517273;14266.286685665778;14331.498397556139;12943.482260709654;13096.952243820471;13209.075364724473;13397.732045359038;13414.597562073055;11690.41547922609;11932.940335298355;11971.221870578385;12141.93929030358;12167;4213.641840443566;4285.400043993844;4292.549282325342;4351.8320226705655;4477.2164871147415;78435.6078219611;80645;81160.18839811644;83178;83442.58278708567;10351.171906247482;10530.420826854597;10536.420496872746;10555.313904596176;10706.89293107059;20172.09225584852;20232.797672023386;20403.4899127523;20415.693764593543;20435.693464598044;7149.177844547839;7329.633518324063;7390.852182956418;7542.6605802739;7551.924480755232;3925.430812532167;3965.7620542767527;3986.760794352348;4018.3570628699267;4021.6380525752825;3268.950965735516;3354.681305275995;3394.4568868981337;3399.2521645238007;3409.318136372686;602.9939700602938;653.4543656047159;663.2074670768482;693.968771405288;704.8766465868453;568.8634727665413;592.3720855892767;611.810338794975;655.374670816378;668.204836244867;8923.375363724477;8930.285577153942;8966.462012279153;9169.908300917039;9268.582913768896;633.8276923995373;640.3340525853154;656.143732429175;665.0755449924609;668.5988406955885;4115.464989551339;4223.957760422418;4248.129133527619;4259.297215959394;4555.40779698637;74108;90530.54734726416;94699.52650236705;103051;108140.45929770458;92228.42806367722;93746.0625393751;93953;95913;99522.50238748905;75220.87168692474;79484.6025769879;80569;80988.59505702434;82277.58861205656;8495.74512764618;8619.827603447771;8625.094365091574;8645.80678370977;8705.825883482168;51387.743061284455;52640;53125.46874531205;53810.730946345015;57104.1434378493;61368.69315653393;73537;79619;82204.94487637856;84456.15543844482;13235.404406801525;13333.333333333489;13566.38951247216;13584.456621735415;13604.65988350299;13233.867661323264;13361.599152025456;13863.930680346533;13915.304234788422;14169.22069286199;12744;12808.743825123633;13429.731405372033;13503.59489215325;13760;12894.806577901152;13569.253691047088;13868;13959.651008724863;14454.566363009126;12199.634010979686;13493.32533373347;13641.658958526115;13777.242251675852;14095.718085638026;10724.785504289714;11007.394593297446;11559.479823408135;11645.126615503701;12028.518859245532;10554.525046372779;11368;11406.885931140914;11548.133889958455;11658.88341116578 14:05:30 INFO - PROCESS | 1915 | __end_tp_report 14:05:30 INFO - PROCESS | 1915 | __start_cc_report 14:05:30 INFO - PROCESS | 1915 | _x_x_mozilla_cycle_collect,2243 14:05:30 INFO - PROCESS | 1915 | __end_cc_report 14:05:30 INFO - PROCESS | 1915 | __startTimestamp1481839530817__endTimestamp 14:05:30 INFO - PROCESS | 1915 | 14:05:30 INFO - PROCESS | 1915 | ------- Summary: start ------- 14:05:30 INFO - PROCESS | 1915 | Number of tests: 6 14:05:30 INFO - PROCESS | 1915 | 14:05:30 INFO - PROCESS | 1915 | [#0] dojo.html Cycles:165 Average:5718.14 Median:5874.21 stddev:647.20 (11.0%) stddev-sans-first:620.81 14:05:30 INFO - PROCESS | 1915 | Values: 3301.8 3309.6 3318.1 3335.3 3366.2 5683.4 5760.9 5773.2 5775.8 5820.6 5674.9 5778.9 5782.3 5815.9 5820.2 5177.0 5224.0 5233.6 5248.0 5286.7 5182.9 5191.9 5269.6 5307.5 5312.9 5122.5 5228.6 5231.1 5261.4 5273.9 4556.4 5260.6 5273.4 5275.8 5284.3 5112.9 5213.3 5223.2 5223.9 5262.8 5797.8 5815.7 5815.9 5820.2 5827.2 6056.2 6097.0 6098.0 6098.1 6100.3 6147.5 6155.8 6158.5 6158.9 6215.5 5726.6 5730.9 5773.5 5779.6 5799.8 6284.0 6284.7 6285.4 6306.5 6313.3 5983.2 6035.2 6044.3 6045.7 6083.7 6109.3 6152.0 6235.2 6239.8 6253.8 6211.6 6243.8 6246.3 6249.6 6276.5 6021.9 6083.4 6095.2 6256.6 6285.2 4839.9 4889.4 4904.7 4928.1 4940.6 4388.3 4402.4 4413.3 4419.1 4436.4 5580.6 5785.8 5798.9 5826.1 5838.9 6185.8 6189.3 6232.0 6261.4 6263.6 5791.7 5794.2 5807.2 5818.4 5839.4 4773.7 5342.1 5362.9 5380.2 5393.1 6144.8 6256.6 6257.6 6287.0 6310.0 6193.0 6248.9 6262.9 6297.7 6317.0 6280.1 6282.0 6288.3 6289.5 6301.9 4588.7 6072.7 6204.9 6212.1 6237.8 6153.2 6175.3 6187.8 6208.7 6213.4 5984.0 6107.1 6119.5 6119.5 6119.6 5978.4 6100.6 6114.8 6118.5 6142.5 4880.2 5779.3 5844.2 5874.2 5964.8 6083.1 6116.7 6119.5 6122.2 6123.3 5765.6 5821.4 5836.6 5878.1 5925.5 14:05:30 INFO - PROCESS | 1915 | 14:05:30 INFO - PROCESS | 1915 | [#1] ext.html Cycles:180 Average:64974.93 Median:38296.43 stddev:143679.56 (375.2%) stddev-sans-first:144001.01 14:05:30 INFO - PROCESS | 1915 | Values: 476.7 480.2 491.0 503.5 522.5 23599.3 37961.6 39022.6 39140.8 40599.0 39607.8 39863.0 40022.4 40079.6 40099.0 9185.8 9278.0 9342.1 9347.3 9378.7 9224.0 9319.5 9327.6 9333.6 9339.9 9279.0 9319.6 9324.4 9336.3 9346.0 9247.4 9337.4 9342.1 9349.4 9367.7 9306.7 9360.7 9363.6 9382.8 9396.1 37962.6 38303.4 38319.4 38327.0 38455.0 3182.4 3219.3 3229.1 3234.6 3297.9 28134.7 28330.7 29346.7 29348.6 29799.0 2660.3 3109.8 3156.9 3195.3 3236.8 345467.0 346829.0 347330.0 347583.0 349576.0 54345.0 54654.2 54711.5 54723.0 54778.7 29850.4 33022.3 33629.7 35202.8 56996.4 15465.2 15644.8 15662.2 15671.3 15675.1 11984.5 12068.4 12105.3 12109.0 12117.7 18331.9 18337.0 18348.9 18366.6 18391.2 7322.7 7466.8 7468.7 7478.6 7489.7 820.4 881.4 921.4 937.0 1091.7 28040.7 28354.4 28924.3 29174.1 29197.4 949.4 1023.2 1038.0 1084.2 1127.5 14385.6 14799.6 15041.6 15144.1 15244.5 611164.0 618063.0 619657.9 619797.0 621525.0 53063.4 53576.5 53704.5 53756.0 53882.2 1356.4 1374.5 1387.4 1391.0 1395.6 28064.7 28568.7 28701.3 29084.9 29256.3 46444.8 51195.7 53010.2 53019.0 56608.0 594877.0 600974.0 601927.0 602543.0 603143.0 39434.6 39785.0 40211.4 40401.0 40488.6 43421.0 43617.6 43889.6 44096.7 44148.6 36425.6 36464.0 37289.8 38289.4 38431.6 36632.6 36654.4 36818.0 37101.0 37522.6 30981.8 31786.4 31924.2 34851.7 35228.3 28856.6 32484.8 33475.0 34665.0 34694.1 24507.6 24791.8 24883.6 25352.5 25529.4 14:05:30 INFO - PROCESS | 1915 | 14:05:30 INFO - PROCESS | 1915 | [#2] jquery.html Cycles:180 Average:34197.78 Median:13568.58 stddev:97604.02 (719.3%) stddev-sans-first:97844.73 14:05:30 INFO - PROCESS | 1915 | Values: 347.0 364.5 368.3 376.3 393.9 14516.0 14841.5 15553.3 19214.8 19819.5 15980.0 18827.4 18917.5 20047.9 20056.0 5665.2 5996.7 6006.4 6011.5 6013.1 5614.4 5823.0 5859.6 5886.4 5891.2 5051.2 5777.1 5840.7 5845.6 5859.9 5570.8 5788.1 5859.4 5862.1 5865.0 4921.5 5124.6 5131.5 5133.2 5145.2 21207.8 24696.9 24931.5 24993.8 25078.7 2339.3 2369.8 2409.3 2440.3 2840.8 14535.9 14876.4 15082.6 15701.7 15805.1 1749.6 1782.5 1827.5 1858.9 1931.0 87791.0 90675.2 107060.5 107307.0 108759.0 10442.3 10715.3 10814.2 10862.6 11000.4 13398.6 13407.5 13470.9 13482.4 13546.7 8340.4 8383.8 8419.1 8465.2 8555.7 7106.9 8365.0 8395.6 8541.8 8601.7 6334.2 6454.7 6466.8 6482.5 6484.4 1395.1 1736.7 1772.3 1773.2 1779.9 900.6 914.0 930.9 968.2 998.8 17156.2 17809.7 20022.7 21767.5 21826.9 936.5 952.0 988.8 1015.6 1040.2 9329.7 9498.9 10756.1 11101.9 11396.0 572618.0 578100.0 582137.0 598321.0 598397.0 107120.0 113585.4 128977.4 132673.0 145943.0 927.5 943.7 988.1 1014.8 1018.1 15068.4 18228.7 18585.0 19098.6 19257.3 53149.7 54365.0 55941.4 56495.7 58150.7 80048.6 82173.6 82836.6 83400.0 83521.6 7108.5 7820.4 7847.1 7902.9 8399.8 7193.4 10075.5 10531.3 12140.0 12614.4 8495.7 11343.8 11447.6 11468.5 11517.3 11008.9 11078.0 11323.7 11541.7 11613.8 10528.3 10545.9 10657.6 10669.8 10955.0 8040.6 8246.1 11727.2 11801.7 12178.8 10942.8 11458.4 11809.4 11917.8 12037.4 14:05:30 INFO - PROCESS | 1915 | 14:05:30 INFO - PROCESS | 1915 | [#3] mootools.html Cycles:180 Average:5392.49 Median:5137.02 stddev:2967.94 (57.8%) stddev-sans-first:2959.80 14:05:30 INFO - PROCESS | 1915 | Values: 1233.1 1277.1 1289.5 1311.3 1594.7 7398.8 7735.2 8114.2 8382.7 8416.2 8125.1 8225.2 8235.9 8247.1 8256.7 2263.6 2286.9 2290.3 2296.6 2306.2 2298.7 2331.0 2355.3 2356.2 2357.7 2288.5 2295.1 2318.8 2319.1 2326.8 2244.9 2265.2 2293.4 2295.9 2296.7 1943.5 2313.1 2327.1 2333.2 2339.0 8191.8 8241.7 8277.7 8284.0 8338.2 5009.9 5211.0 5234.5 5248.9 5274.4 3030.4 3488.7 3555.4 3590.8 3596.3 1061.5 1091.6 1429.5 1724.6 1811.4 10942.9 11003.3 11004.8 11014.3 11024.1 3661.7 4417.4 4466.4 4522.0 4526.9 8692.2 8698.2 8707.0 8711.7 8713.8 5124.2 5130.5 5143.6 5152.7 5152.8 4017.4 4274.2 4407.3 4758.3 4812.4 3623.0 3634.7 3708.4 3716.0 3726.9 1110.1 1133.7 1144.8 1145.4 1146.5 834.1 847.0 887.6 895.2 912.6 5428.9 5461.3 5616.9 5630.0 5800.2 823.8 851.3 857.8 866.9 896.8 3598.4 3771.9 3882.8 4059.4 4067.4 9488.4 9570.3 10172.6 10317.7 10328.7 10015.9 10255.5 10276.3 10295.8 10313.9 7559.8 7908.0 7926.0 7940.6 7950.8 5333.0 5373.8 5380.4 5439.4 5673.2 9251.4 9326.8 9376.5 9441.8 9496.3 9196.6 9518.1 9577.0 9661.9 9691.5 7058.3 7086.8 7235.2 7255.0 7271.5 7336.4 7340.4 7372.2 7392.7 7433.8 6945.6 7027.8 7094.6 7144.8 7197.7 6950.0 6961.0 6998.4 7037.9 7042.4 6560.0 6598.9 6635.3 6659.0 6697.7 4342.3 4602.5 4813.3 4815.2 4973.7 4297.7 4642.0 4815.2 4921.5 4996.7 14:05:30 INFO - PROCESS | 1915 | 14:05:30 INFO - PROCESS | 1915 | [#4] prototype.html Cycles:180 Average:4322.58 Median:4835.20 stddev:1362.39 (28.2%) stddev-sans-first:1331.73 14:05:30 INFO - PROCESS | 1915 | Values: 264.7 300.7 306.1 311.3 314.7 4302.4 4520.3 5195.1 5237.0 5263.3 3994.5 4881.0 5132.0 5184.5 5207.5 3161.2 3236.6 3802.8 3849.5 3856.5 3310.5 3765.1 3803.4 3912.5 3922.2 3561.9 3599.5 3766.7 3859.7 3936.5 3402.0 3405.4 3792.6 3822.7 3905.4 3027.6 3451.5 3454.9 3470.5 3491.9 4358.0 4406.4 5012.2 5033.3 5041.7 4507.6 4518.9 4523.7 4557.6 4578.5 4532.4 4757.5 5248.7 5428.8 5538.9 3226.4 3390.3 3691.0 3832.5 3853.4 4633.7 4964.0 5583.8 5733.5 5753.4 3259.0 3670.5 3803.5 3897.0 3921.2 3324.7 3377.1 3563.5 3945.8 3952.7 3558.7 3743.3 3793.6 4181.3 4210.7 4458.7 5282.4 5535.4 5537.9 5545.0 1138.2 1202.7 1204.0 1285.5 1290.4 771.2 871.1 877.8 899.6 899.7 4898.7 4901.3 5102.0 5136.6 5281.1 4821.5 5375.7 5630.9 5686.5 5711.9 4292.7 4848.9 5154.6 5207.5 5225.7 2433.5 2510.7 2562.9 2661.8 2738.5 5062.6 5458.1 5489.8 5510.9 5517.9 4461.5 5060.8 5374.9 5444.6 5817.4 5567.0 5813.4 5859.4 5931.2 5951.0 4707.7 5025.8 5739.8 5745.2 5769.9 4383.6 4750.4 5055.6 5110.7 5560.5 5051.9 5148.3 5235.4 5639.8 5814.7 3202.0 3774.4 3796.6 3796.8 3862.7 4758.5 5391.3 5397.6 5575.0 5683.7 4314.0 5371.5 5441.7 5458.8 5585.3 5389.3 5450.5 5462.6 5480.0 5507.8 4546.3 4733.6 5029.2 5353.2 5418.7 5196.5 5293.7 5416.3 5418.4 5491.1 4517.4 4547.9 5471.9 5496.8 5497.9 14:05:30 INFO - PROCESS | 1915 | 14:05:30 INFO - PROCESS | 1915 | [#5] yui.html Cycles:180 Average:19869.50 Median:4516.31 stddev:28182.65 (624.0%) stddev-sans-first:28224.58 14:05:30 INFO - PROCESS | 1915 | Values: 603.2 698.9 707.2 775.7 780.3 13638.5 14424.7 14662.9 14678.0 14701.7 12181.6 12199.2 12288.9 13126.8 13365.1 666.1 814.1 825.0 825.8 829.4 681.0 796.7 827.1 831.3 833.9 873.0 873.2 873.4 873.8 874.4 693.0 867.9 870.1 870.3 871.9 528.3 621.2 641.1 645.3 647.0 14034.0 14063.0 14242.1 14266.3 14331.5 12943.5 13097.0 13209.1 13397.7 13414.6 11690.4 11932.9 11971.2 12141.9 12167.0 4213.6 4285.4 4292.5 4351.8 4477.2 78435.6 80645.0 81160.2 83178.0 83442.6 10351.2 10530.4 10536.4 10555.3 10706.9 20172.1 20232.8 20403.5 20415.7 20435.7 7149.2 7329.6 7390.9 7542.7 7551.9 3925.4 3965.8 3986.8 4018.4 4021.6 3269.0 3354.7 3394.5 3399.3 3409.3 603.0 653.5 663.2 694.0 704.9 568.9 592.4 611.8 655.4 668.2 8923.4 8930.3 8966.5 9169.9 9268.6 633.8 640.3 656.1 665.1 668.6 4115.5 4224.0 4248.1 4259.3 4555.4 74108.0 90530.5 94699.5 103051.0 108140.5 92228.4 93746.1 93953.0 95913.0 99522.5 75220.9 79484.6 80569.0 80988.6 82277.6 8495.7 8619.8 8625.1 8645.8 8705.8 51387.7 52640.0 53125.5 53810.7 57104.1 61368.7 73537.0 79619.0 82204.9 84456.2 13235.4 13333.3 13566.4 13584.5 13604.7 13233.9 13361.6 13863.9 13915.3 14169.2 12744.0 12808.7 13429.7 13503.6 13760.0 12894.8 13569.3 13868.0 13959.7 14454.6 12199.6 13493.3 13641.7 13777.2 14095.7 10724.8 11007.4 11559.5 11645.1 12028.5 10554.5 11368.0 11406.9 11548.1 11658.9 14:05:30 INFO - PROCESS | 1915 | -------- Summary: end -------- 14:05:30 INFO - PROCESS | 1915 | 14:05:31 INFO - TEST-INFO | 1915: exit 0 14:05:31 INFO - TEST-OK | dromaeo_css | took 1122023ms 14:05:31 INFO - TEST-START | kraken 14:05:31 INFO - operating with platform_type : mac_ 14:05:31 INFO - Initialising browser for kraken test... 14:05:31 INFO - TEST-INFO | started process 1918 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/tmpZQmBMo/profile http://localhost:51293/getInfo.html) 14:05:33 INFO - PROCESS | 1918 | __metrics Screen width/height:1600/1200 14:05:33 INFO - PROCESS | 1918 | colorDepth:24 14:05:33 INFO - PROCESS | 1918 | Browser inner width/height: 1024/689 14:05:33 INFO - PROCESS | 1918 | __metrics 14:05:34 INFO - PROCESS | 1918 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:05:34 INFO - PROCESS | 1918 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:05:34 INFO - TEST-INFO | 1918: exit 0 14:05:34 INFO - Browser initialized. 14:05:34 INFO - Running cycle 1/1 for kraken test... 14:05:34 INFO - TEST-INFO | started process 1919 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/tmpZQmBMo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:05:47 INFO - PROCESS | 1919 | RSS: Main: 239607808 14:05:47 INFO - PROCESS | 1919 | 14:05:50 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:51293/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51293/tests/kraken/driver.html?testName=audio-beat-detection) 14:05:51 INFO - PROCESS | 1919 | RSS: Main: 284782592 14:05:51 INFO - PROCESS | 1919 | 14:05:53 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:51293/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51293/tests/kraken/driver.html?testName=audio-dft) 14:05:54 INFO - PROCESS | 1919 | RSS: Main: 298196992 14:05:54 INFO - PROCESS | 1919 | 14:05:57 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:51293/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51293/tests/kraken/driver.html?testName=audio-fft) 14:05:58 INFO - PROCESS | 1919 | RSS: Main: 390995968 14:05:58 INFO - PROCESS | 1919 | 14:06:00 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:51293/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51293/tests/kraken/driver.html?testName=audio-oscillator) 14:06:00 INFO - PROCESS | 1919 | RSS: Main: 294268928 14:06:00 INFO - PROCESS | 1919 | 14:06:02 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:51293/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51293/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:06:03 INFO - PROCESS | 1919 | RSS: Main: 295518208 14:06:03 INFO - PROCESS | 1919 | 14:06:06 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:51293/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51293/tests/kraken/driver.html?testName=imaging-darkroom) 14:06:07 INFO - PROCESS | 1919 | RSS: Main: 336490496 14:06:07 INFO - PROCESS | 1919 | 14:06:10 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:51293/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51293/tests/kraken/driver.html?testName=imaging-desaturate) 14:06:10 INFO - PROCESS | 1919 | RSS: Main: 337190912 14:06:10 INFO - PROCESS | 1919 | 14:06:13 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:51293/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51293/tests/kraken/driver.html?testName=json-parse-financial) 14:06:14 INFO - PROCESS | 1919 | RSS: Main: 335659008 14:06:14 INFO - PROCESS | 1919 | 14:06:15 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:51293/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51293/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:06:16 INFO - PROCESS | 1919 | RSS: Main: 308916224 14:06:16 INFO - PROCESS | 1919 | 14:06:18 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:51293/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51293/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:06:18 INFO - PROCESS | 1919 | RSS: Main: 311365632 14:06:18 INFO - PROCESS | 1919 | 14:06:21 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:51293/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51293/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:06:21 INFO - PROCESS | 1919 | RSS: Main: 314048512 14:06:21 INFO - PROCESS | 1919 | 14:06:24 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:51293/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51293/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:06:25 INFO - PROCESS | 1919 | RSS: Main: 328855552 14:06:25 INFO - PROCESS | 1919 | 14:06:28 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:51293/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51293/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:06:29 INFO - PROCESS | 1919 | RSS: Main: 322154496 14:06:29 INFO - PROCESS | 1919 | 14:06:30 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:51293/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51293/tests/kraken/driver.html?testName=ai-astar) 14:06:31 INFO - PROCESS | 1919 | RSS: Main: 367005696 14:06:31 INFO - PROCESS | 1919 | 14:06:31 INFO - PROCESS | 1919 | __start_tp_report 14:06:31 INFO - PROCESS | 1919 | _x_x_mozilla_page_load 14:06:31 INFO - PROCESS | 1919 | _x_x_mozilla_page_load_details 14:06:31 INFO - PROCESS | 1919 | |i|pagename|runs| 14:06:31 INFO - PROCESS | 1919 | |0;ai-astar;102;114;114;114;113;114;114;114;114;114 14:06:31 INFO - PROCESS | 1919 | |1;audio-beat-detection;149;114;139;175;111;111;111;111;141;109 14:06:31 INFO - PROCESS | 1919 | |2;audio-dft;184;185;183;186;185;186;183;186;182;184 14:06:31 INFO - PROCESS | 1919 | |3;audio-fft;82;59;59;90;67;60;59;60;83;60 14:06:31 INFO - PROCESS | 1919 | |4;audio-oscillator;87;71;127;99;54;73;123;97;98;106 14:06:31 INFO - PROCESS | 1919 | |5;imaging-gaussian-blur;74;112;133;128;125;86;89;86;90;87 14:06:31 INFO - PROCESS | 1919 | |6;imaging-darkroom;96;97;97;97;97;126;159;151;95;114 14:06:31 INFO - PROCESS | 1919 | |7;imaging-desaturate;82;83;80;80;81;82;86;82;81;82 14:06:31 INFO - PROCESS | 1919 | |8;json-parse-financial;55;55;56;55;56;58;58;58;58;58 14:06:31 INFO - PROCESS | 1919 | |9;json-stringify-tinderbox;65;66;77;76;73;72;72;73;72;73 14:06:31 INFO - PROCESS | 1919 | |10;stanford-crypto-aes;84;84;88;100;99;83;82;83;83;92 14:06:31 INFO - PROCESS | 1919 | |11;stanford-crypto-ccm;143;153;152;142;141;150;152;152;191;143 14:06:31 INFO - PROCESS | 1919 | |12;stanford-crypto-pbkdf2;165;165;165;166;166;163;165;166;214;219 14:06:31 INFO - PROCESS | 1919 | |13;stanford-crypto-sha256-iterative;68;67;68;68;66;67;67;65;68;67 14:06:31 INFO - PROCESS | 1919 | __end_tp_report 14:06:31 INFO - PROCESS | 1919 | __start_cc_report 14:06:31 INFO - PROCESS | 1919 | _x_x_mozilla_cycle_collect,2141 14:06:31 INFO - PROCESS | 1919 | __end_cc_report 14:06:31 INFO - PROCESS | 1919 | __startTimestamp1481839591124__endTimestamp 14:06:31 INFO - PROCESS | 1919 | 14:06:31 INFO - PROCESS | 1919 | ------- Summary: start ------- 14:06:31 INFO - PROCESS | 1919 | Number of tests: 14 14:06:31 INFO - PROCESS | 1919 | 14:06:31 INFO - PROCESS | 1919 | [#0] ai-astar Cycles:10 Average:112.70 Median:114.00 stddev:3.77 (3.3%) stddev-sans-first:0.33 14:06:31 INFO - PROCESS | 1919 | Values: 102.0 114.0 114.0 114.0 113.0 114.0 114.0 114.0 114.0 114.0 14:06:31 INFO - PROCESS | 1919 | 14:06:31 INFO - PROCESS | 1919 | [#1] audio-beat-detection Cycles:10 Average:127.10 Median:126.50 stddev:22.72 (18.0%) stddev-sans-first:22.67 14:06:31 INFO - PROCESS | 1919 | Values: 149.0 114.0 139.0 175.0 111.0 111.0 111.0 111.0 141.0 109.0 14:06:31 INFO - PROCESS | 1919 | 14:06:31 INFO - PROCESS | 1919 | [#2] audio-dft Cycles:10 Average:184.40 Median:185.00 stddev:1.43 (0.8%) stddev-sans-first:1.51 14:06:31 INFO - PROCESS | 1919 | Values: 184.0 185.0 183.0 186.0 185.0 186.0 183.0 186.0 182.0 184.0 14:06:31 INFO - PROCESS | 1919 | 14:06:31 INFO - PROCESS | 1919 | [#3] audio-fft Cycles:10 Average:67.90 Median:63.50 stddev:12.21 (19.2%) stddev-sans-first:11.83 14:06:31 INFO - PROCESS | 1919 | Values: 82.0 59.0 59.0 90.0 67.0 60.0 59.0 60.0 83.0 60.0 14:06:31 INFO - PROCESS | 1919 | 14:06:31 INFO - PROCESS | 1919 | [#4] audio-oscillator Cycles:10 Average:93.50 Median:80.00 stddev:22.95 (28.7%) stddev-sans-first:24.22 14:06:31 INFO - PROCESS | 1919 | Values: 87.0 71.0 127.0 99.0 54.0 73.0 123.0 97.0 98.0 106.0 14:06:31 INFO - PROCESS | 1919 | 14:06:31 INFO - PROCESS | 1919 | [#5] imaging-gaussian-blur Cycles:10 Average:101.00 Median:86.00 stddev:21.32 (24.8%) stddev-sans-first:20.25 14:06:31 INFO - PROCESS | 1919 | Values: 74.0 112.0 133.0 128.0 125.0 86.0 89.0 86.0 90.0 87.0 14:06:31 INFO - PROCESS | 1919 | 14:06:31 INFO - PROCESS | 1919 | [#6] imaging-darkroom Cycles:10 Average:112.90 Median:96.50 stddev:24.42 (25.3%) stddev-sans-first:25.12 14:06:31 INFO - PROCESS | 1919 | Values: 96.0 97.0 97.0 97.0 97.0 126.0 159.0 151.0 95.0 114.0 14:06:31 INFO - PROCESS | 1919 | 14:06:31 INFO - PROCESS | 1919 | [#7] imaging-desaturate Cycles:10 Average:81.90 Median:82.00 stddev:1.73 (2.1%) stddev-sans-first:1.83 14:06:31 INFO - PROCESS | 1919 | Values: 82.0 83.0 80.0 80.0 81.0 82.0 86.0 82.0 81.0 82.0 14:06:31 INFO - PROCESS | 1919 | 14:06:31 INFO - PROCESS | 1919 | [#8] json-parse-financial Cycles:10 Average:56.70 Median:58.00 stddev:1.42 (2.4%) stddev-sans-first:1.36 14:06:31 INFO - PROCESS | 1919 | Values: 55.0 55.0 56.0 55.0 56.0 58.0 58.0 58.0 58.0 58.0 14:06:31 INFO - PROCESS | 1919 | 14:06:31 INFO - PROCESS | 1919 | [#9] json-stringify-tinderbox Cycles:10 Average:71.90 Median:73.00 stddev:3.78 (5.2%) stddev-sans-first:3.08 14:06:31 INFO - PROCESS | 1919 | Values: 65.0 66.0 77.0 76.0 73.0 72.0 72.0 73.0 72.0 73.0 14:06:31 INFO - PROCESS | 1919 | 14:06:31 INFO - PROCESS | 1919 | [#10] stanford-crypto-aes Cycles:10 Average:87.80 Median:84.00 stddev:6.86 (8.2%) stddev-sans-first:7.14 14:06:31 INFO - PROCESS | 1919 | Values: 84.0 84.0 88.0 100.0 99.0 83.0 82.0 83.0 83.0 92.0 14:06:31 INFO - PROCESS | 1919 | 14:06:31 INFO - PROCESS | 1919 | [#11] stanford-crypto-ccm Cycles:10 Average:151.90 Median:152.00 stddev:14.56 (9.6%) stddev-sans-first:15.09 14:06:31 INFO - PROCESS | 1919 | Values: 143.0 153.0 152.0 142.0 141.0 150.0 152.0 152.0 191.0 143.0 14:06:31 INFO - PROCESS | 1919 | 14:06:31 INFO - PROCESS | 1919 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.40 Median:166.00 stddev:21.71 (13.1%) stddev-sans-first:22.70 14:06:31 INFO - PROCESS | 1919 | Values: 165.0 165.0 165.0 166.0 166.0 163.0 165.0 166.0 214.0 219.0 14:06:31 INFO - PROCESS | 1919 | 14:06:31 INFO - PROCESS | 1919 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.10 Median:67.50 stddev:0.99 (1.5%) stddev-sans-first:1.00 14:06:31 INFO - PROCESS | 1919 | Values: 68.0 67.0 68.0 68.0 66.0 67.0 67.0 65.0 68.0 67.0 14:06:31 INFO - PROCESS | 1919 | -------- Summary: end -------- 14:06:31 INFO - PROCESS | 1919 | 14:06:32 INFO - TEST-INFO | 1919: exit 0 14:06:32 INFO - TEST-OK | kraken | took 60766ms 14:06:32 INFO - SUITE-END | took 1182s 14:06:32 INFO - Completed test suite (00:19:42) 14:06:32 INFO - javascript benchmark 14:06:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3301.8183999880002, 3309.626505000425, 3318.1372843060813, 3335.316260166666, 3366.1921138926664, 5683.431656834319, 5760.855978600537, 5773.249477567911, 5775.826725198241, 5820.563457740675, 5674.943250567503, 5778.855528611787, 5782.277215348082, 5815.941840581603, 5820.214271073414, 5177.042247184283, 5223.981323641891, 5233.58131349493, 5247.973760131194, 5286.656367336111, 5182.922256166161, 5191.896162076756, 5269.6311258211945, 5307.548858347045, 5312.893742125155, 5122.462141475147, 5228.5555727763185, 5231.136862417697, 5261.421243663194, 5273.8945221095555, 4556.3621093047, 5260.579153667697, 5273.4462881397285, 5275.762590683428, 5284.260203571523, 5112.897742045138, 5213.348331458567, 5223.242629818655, 5223.869403264911, 5262.7631756571045, 5797.768089276424, 5815.709214539256, 5815.88368232633, 5820.214271073393, 5827.184194212814, 6056.182415373956, 6097.024476083805, 6098.0, 6098.14625952367, 6100.267967843843, 6147.524594097429, 6155.815325540196, 6158.507319414481, 6158.8768224635705, 6215.502759779207, 5726.627769194989, 5730.885382292373, 5773.509251713609, 5779.595428320019, 5799.797007104731, 6283.96858015707, 6284.654344011124, 6285.371462853724, 6306.49548036156, 6313.273973493016, 5983.1623572699855, 6035.245594300713, 6044.274687037584, 6045.667488288098, 6083.726232319556, 6109.34393638171, 6152.046432802923, 6235.189425374672, 6239.844003899848, 6253.781117660951, 6211.596246243979, 6243.7814676486105, 6246.312905580383, 6249.625022498573, 6276.46650034743, 6021.939780602138, 6083.39166083397, 6095.207623008981, 6256.593321434092, 6285.182926219653, 4839.879003024953, 4889.437714662859, 4904.656674032854, 4928.112939670822, 4940.580050695659, 4388.29787234041, 4402.449693788277, 4413.271810151353, 4419.071994881111, 4436.44544431946, 5580.553555715533, 5785.797497087582, 5798.9130163047585, 5826.096954971986, 5838.854028649318, 6185.8144255672405, 6189.319174890758, 6231.968840155861, 6261.373862613702, 6263.592866463665, 5791.652500849878, 5794.159846822187, 5807.186993820868, 5818.389069147764, 5839.357670656224, 4773.71357718538, 5342.070301964816, 5362.946370536322, 5380.219868119101, 5393.056215162331, 6144.818589400698, 6256.562040657111, 6257.624542527463, 6286.968565157145, 6310.0, 6193.0, 6248.875022499616, 6262.874742505215, 6297.653629050263, 6316.968415157895, 6280.089387038763, 6281.9948808190475, 6288.339724329063, 6289.528285378524, 6301.936980630135, 4588.701734387254, 6072.666003369857, 6204.875902482016, 6212.06818977157, 6237.75048998035, 6153.230846144232, 6175.289841668266, 6187.783427580013, 6208.72060757258, 6213.440790328892, 5983.970080149571, 6107.053406721965, 6119.4798442132915, 6119.541034422347, 6119.571629985859, 5978.372270911492, 6100.572959892942, 6114.755409783559, 6118.5411094169, 6142.508599311955, 4880.243562247857, 5779.306483221872, 5844.181814545882, 5874.206982057539, 5964.7912323068485, 6083.117947897442, 6116.701002199415, 6119.531596328132, 6122.184701511036, 6123.343628574382, 5765.625234359753, 5821.38875418075, 5836.562257830765, 5878.147668587945, 5925.496332811759], "value": 5676.871899302053, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [476.7258826174948, 480.1812908990172, 490.95916656687825, 503.4763845600575, 522.4984015345268, 23599.292021239347, 37961.62038379615, 39022.60977390225, 39140.80429597848, 40598.98502537438, 39607.801960990226, 39863.0, 40022.39966400506, 40079.59920400795, 40099.0, 9185.805845240126, 9277.95361023194, 9342.065793420637, 9347.345685801996, 9378.718638440858, 9224.0, 9319.534023298842, 9327.62689492423, 9333.626654933794, 9339.906600934006, 9279.025702301262, 9319.580618872167, 9324.440533567973, 9336.299777516679, 9346.0, 9247.39891907024, 9337.393069450532, 9342.112499312554, 9349.39228950123, 9367.672131475434, 9306.72079837606, 9360.672376466859, 9363.578638961262, 9382.765430864214, 9396.107369799858, 37962.62037379646, 38303.42544861829, 38319.42520862189, 38327.0, 38455.0, 3182.427163110639, 3219.3400352927592, 3229.128135403428, 3234.56333394991, 3297.868085276586, 28134.718652813415, 28330.716692833015, 29346.70653293461, 29348.559771603443, 29799.0, 2660.2950218192195, 3109.8134111953127, 3156.925679878575, 3195.2970346523725, 3236.8381580921096, 345467.0, 346829.0, 347330.0, 347582.9999999975, 349576.0, 54345.0, 54654.18018729722, 54711.45288547143, 54723.0, 54778.72610636921, 29850.402991940475, 33022.3395532088, 33629.654813807174, 35202.823985879906, 56996.430035699945, 15465.226738663247, 15644.843551564567, 15662.216889155497, 15671.294791734399, 15675.137867417401, 11984.520619175135, 12068.396580170956, 12105.33420661872, 12109.03127749778, 12117.697057573454, 18331.908340458478, 18336.991465469262, 18348.908255458904, 18366.632667346577, 18391.172397242157, 7322.743703970335, 7466.813329666693, 7468.738594149178, 7478.551286922802, 7489.662965166581, 820.4175035724583, 881.4402854187637, 921.3504479342048, 936.9765755856158, 1091.7434402915308, 28040.71959280422, 28354.432911341657, 28924.276893077422, 29174.124776256747, 29197.416051678847, 949.4350861237691, 1023.1507848485887, 1037.994810025945, 1084.1976937066513, 1127.5116845763678, 14385.568432947028, 14799.556013319618, 15041.623959400884, 15144.091354518765, 15244.542663720105, 611164.0, 618063.0, 619657.9017104886, 619797.0, 621525.0, 53063.40809775713, 53576.464235357926, 53704.46295537073, 53756.0, 53882.19176712353, 1356.4099616666583, 1374.5326588959801, 1387.3964825301027, 1390.9582712518843, 1395.5534229046607, 28064.71935280621, 28568.714312857435, 28701.28246793763, 29084.854575726986, 29256.26859328534, 46444.7677761609, 51195.74402127966, 53010.20484692656, 53019.0, 56608.0, 594877.0, 600974.0, 601927.0, 602543.0, 603143.0, 39434.605653943094, 39785.0, 40211.39682904817, 40401.0, 40488.59511404848, 43421.0, 43617.563824362616, 43889.561104389824, 44096.67709968706, 44148.55851441444, 36425.635743642226, 36464.0, 37289.81355093207, 38289.425658614586, 38431.61568384392, 36632.633673662924, 36654.42895660992, 36818.0, 37101.0, 37522.624773751915, 30981.845090775303, 31786.36427271488, 31924.201894952814, 34851.65148348484, 35228.295434091684, 28856.567151492323, 32484.83757581197, 33474.995750127535, 34665.0, 34694.13264668403, 24507.632385513876, 24791.752082479667, 24883.626745598467, 25352.492950141263, 25529.361765956], "value": 19033.75384302829, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.04735501049623, 364.50791431567404, 368.3278017617847, 376.29632587062196, 393.87396033269346, 14516.0, 14841.480548180816, 15553.300101495432, 19214.807851921476, 19819.504512387197, 15979.961302515328, 18827.43517694468, 18917.527061823428, 20047.899760501175, 20056.0, 5665.150227465892, 5996.670183139926, 6006.369331220225, 6011.489023433013, 6013.098035294697, 5614.3543492498475, 5823.0, 5859.6484210947265, 5886.352501224862, 5891.175235467038, 5051.166557518005, 5777.133429985514, 5840.678762668052, 5845.620034697731, 5859.853503662422, 5570.777168913239, 5788.073908174714, 5859.384764599741, 5862.120681897707, 5865.0, 4921.458639549646, 5124.615653825978, 5131.461196574343, 5133.204353325241, 5145.176771716508, 21207.78792212089, 24696.876515617485, 24931.50136997268, 24993.750062499505, 25078.749212507824, 2339.263132113379, 2369.8104151667826, 2409.265174121892, 2440.280117365364, 2840.758535524483, 14535.927320363437, 14876.404943802236, 15082.622934426618, 15701.685966280611, 15805.051696898108, 1749.5538637647446, 1782.4652604218682, 1827.4700336902283, 1858.8884666919894, 1930.9517262068562, 87791.0, 90675.22518270487, 107060.46469767601, 107307.0, 108759.0, 10442.321249118782, 10715.303505272132, 10814.188935829845, 10862.565497380174, 11000.394978276272, 13398.598042058753, 13407.530736424178, 13470.93264533671, 13482.39329230187, 13546.661333466545, 8340.416170868102, 8383.790405239917, 8419.07390187079, 8465.238128568335, 8555.657773688983, 7106.857862842714, 8364.958175209207, 8395.580220988926, 8541.829163416696, 8601.655933762579, 6334.239891213084, 6454.677266136674, 6466.805995820133, 6482.513811464159, 6484.351564843572, 1395.1026603818798, 1736.6526694661118, 1772.3087995681694, 1773.2463702926204, 1779.8665100117544, 900.5902314446911, 913.9725808225763, 930.9394889332171, 968.1818863060703, 998.8252055890335, 17156.227969741143, 17809.7328540072, 20022.69965950512, 21767.455813604785, 21826.890865545887, 936.4989730494153, 952.0, 988.7972965542046, 1015.6292953072076, 1040.1834559870465, 9329.673461428953, 9498.90501094994, 10756.08573271265, 11101.944490277498, 11396.0, 572618.0, 578100.0, 582137.0, 598321.0, 598397.0, 107120.0, 113585.4320728391, 128977.35511322384, 132673.0, 145943.0, 927.5408672707053, 943.6932996776076, 988.0564061321421, 1014.8325526288228, 1018.104068419771, 15068.397264109752, 18228.726569101207, 18585.0, 19098.61802763909, 19257.32599359016, 53149.671258218856, 54365.0, 55941.40585941373, 56495.71752141213, 58150.709246453494, 80048.59975700318, 82173.58913205395, 82836.58581707053, 83400.0, 83521.58239208766, 7108.502404831612, 7820.413468989735, 7847.136814950237, 7902.893594896604, 8399.790005249917, 7193.388561972291, 10075.546600402853, 10531.315464494783, 12139.96810271137, 12614.36928153607, 8495.702650407453, 11343.773124537629, 11447.599334023269, 11468.541258349573, 11517.25137866036, 11008.889911101107, 11078.0, 11323.660290191307, 11541.711457213636, 11613.825792612948, 10528.315659482108, 10545.9472702636, 10657.57369705203, 10669.786604268027, 10955.0, 8040.638171282189, 8246.134155913544, 11727.237729547553, 11801.704957376134, 12178.817317740419, 10942.835857461985, 11458.369789661649, 11809.350485723024, 11917.76164476723, 12037.398130093285], "value": 9540.045609741128, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1233.1491271022994, 1277.11240687722, 1289.5486579697101, 1311.252586025965, 1594.7129516686998, 7398.778036658902, 7735.187805280449, 8114.229148230924, 8382.664693412271, 8416.200460956215, 8125.146859579733, 8225.177482251787, 8235.91764082359, 8247.134050924642, 8256.66973321068, 2263.5586060718124, 2286.862788232703, 2290.3243543154726, 2296.632538793793, 2306.2043594959796, 2298.6781850540856, 2330.96503552446, 2355.3169580821627, 2356.2460012795914, 2357.6581395697613, 2288.4850908545527, 2295.1393227539675, 2318.814494840417, 2319.118734880743, 2326.7789559991775, 2244.876531790751, 2265.161890100665, 2293.4037150341032, 2295.885205739706, 2296.70142881426, 1943.4849764812336, 2313.051648823991, 2327.0924339507606, 2333.171724037959, 2339.040993192783, 8191.79520511992, 8241.711540096068, 8277.668893244263, 8284.0, 8338.207870252316, 5009.874753131165, 5211.0, 5234.450382709799, 5248.8687782805355, 5274.4198138204765, 3030.3787723516625, 3488.66857648523, 3555.3600351936643, 3590.784552926833, 3596.29872174926, 1061.5382308695741, 1091.6343025086605, 1429.5282556756245, 1724.5774785177587, 1811.3660218923403, 10942.890571094347, 11003.33979961197, 11004.834927476095, 11014.284071535325, 11024.118070554417, 3661.7436779425184, 4417.4036505072045, 4466.3523789050405, 4522.0277640307495, 4526.88682782933, 8692.174243446863, 8698.21716045559, 8707.0, 8711.695090671796, 8713.825723485495, 5124.15451450514, 5130.461301563282, 5143.588512918957, 5152.716600587004, 5152.845414637567, 4017.357222844331, 4274.166537525152, 4407.316865885793, 4758.262469317192, 4812.374391329105, 3623.0, 3634.7092232621326, 3708.4066549351974, 3715.9628403716156, 3726.869559565402, 1110.0841805510486, 1133.7279053027214, 1144.7996600594863, 1145.4043897173424, 1146.4726225935997, 834.0700119366862, 847.0301504776996, 887.5784002598714, 895.1809094678297, 912.6030176873081, 5428.945710542923, 5461.315270887169, 5616.85957851057, 5629.971850140805, 5800.187973683687, 823.7693445835175, 851.2508992087047, 857.7769779857282, 866.913308669128, 896.8475359188953, 3598.3522965865873, 3771.9245615088093, 3882.8446862125766, 4059.3910913363234, 4067.4102255173425, 9488.430694158373, 9570.33007689469, 10172.593096276214, 10317.742056448498, 10328.741781455374, 10015.949920250352, 10255.487225638688, 10276.280660353703, 10295.794084118275, 10313.948430257951, 7559.773206803805, 7907.9604601976625, 7926.048874135141, 7940.602969851484, 7950.761477155579, 5333.0, 5373.758180881791, 5380.408155102858, 5439.4288599696465, 5673.205751194753, 9251.444913305224, 9326.813463730823, 9376.531173441164, 9441.811163776823, 9496.287778416785, 9196.632134714537, 9518.095780900803, 9577.0, 9661.903380966382, 9691.466969316472, 7058.25888281723, 7086.822829429306, 7235.2402997684485, 7254.96372518134, 7271.490995630255, 7336.37640800538, 7340.449466290156, 7372.189059203591, 7392.667329970272, 7433.814154645961, 6945.617991010543, 7027.754028608974, 7094.574325540484, 7144.821379465555, 7197.748078817216, 6949.965250173716, 6960.965195173992, 6998.370146686822, 7037.859242815012, 7042.366187043191, 6560.048792925095, 6598.901016484852, 6635.303293154149, 6658.966705166443, 6697.665116744241, 4342.305231162999, 4602.4937256902385, 4813.326134341265, 4815.22956326986, 4973.701577905337, 4297.656187505055, 4642.025174713348, 4815.15734746425, 4921.4832442593015, 4996.675216110941], "value": 4371.715307534435, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [264.72468632622076, 300.66926380980925, 306.06344585568155, 311.3446195757931, 314.66959692323053, 4302.419173411588, 4520.254158063924, 5195.142801437763, 5236.973815130924, 5263.263103186341, 3994.480717506727, 4880.950595621941, 5132.0, 5184.53339199472, 5207.453217412174, 3161.1981170732693, 3236.643969163396, 3802.7943792600363, 3849.499565056539, 3856.46009558662, 3310.486874534439, 3765.0775559987705, 3803.429485577172, 3912.4522566840665, 3922.195949830278, 3561.9492249786376, 3599.478075679025, 3766.6665001473757, 3859.671927886133, 3936.468576742132, 3402.0, 3405.438102713074, 3792.639699228569, 3822.73240873139, 3905.383393126612, 3027.5912751778446, 3451.5340429042008, 3454.9481757773656, 3470.514128022079, 3491.930161396783, 4357.956420435787, 4406.361077643755, 5012.223105418666, 5033.320501732256, 5041.747912604392, 4507.616852567503, 4518.864434066983, 4523.728576285401, 4557.58981691646, 4578.51925547816, 4532.365468834366, 4757.548032936866, 5248.661591294195, 5428.809991650312, 5538.9169162462595, 3226.370857682753, 3390.304987477561, 3690.9665293717803, 3832.5209348831395, 3853.402722578005, 4633.721976681376, 4964.0, 5583.804566840182, 5733.541316694655, 5753.395893431171, 3258.967410325914, 3670.541182352206, 3803.4675145479655, 3897.0, 3921.235359105004, 3324.7173990211104, 3377.071305391037, 3563.483294922222, 3945.8421663133154, 3952.703547233969, 3558.733095017832, 3743.344914639927, 3793.639604237593, 4181.28216659174, 4210.705250632487, 4458.687891847604, 5282.392524859613, 5535.363433205152, 5537.916931246034, 5545.0, 1138.214631904, 1202.705337192376, 1204.00669447706, 1285.517930775959, 1290.3999640167367, 771.182546500711, 871.0984131423946, 877.7893305606736, 899.5592159841631, 899.7030979776789, 4898.681585696918, 4901.324006994754, 5101.979604079199, 5136.614753893473, 5281.128613778684, 4821.493743156988, 5375.650582712189, 5630.887382252414, 5686.516646085046, 5711.942880571224, 4292.6995110342605, 4848.908995475989, 5154.613403994716, 5207.479252074762, 5225.712585807817, 2433.5011322678806, 2510.6736124303725, 2562.923112306634, 2661.7604415602686, 2738.4796888591504, 5062.645614806928, 5458.1266997280245, 5489.752961116759, 5510.91733623996, 5517.917231241535, 4461.5092339842595, 5060.823845746291, 5374.946250537523, 5444.564434845203, 5817.418258174149, 5566.972165139148, 5813.360530341659, 5859.384764599741, 5931.233666621726, 5951.018082016505, 4707.741074240949, 5025.824096156617, 5739.770409183753, 5745.242283703762, 5769.8557536061935, 4383.561643835591, 4750.382450281441, 5055.649123157749, 5110.722319420145, 5560.471755183251, 5051.949480505295, 5148.293228875204, 5235.3717553893775, 5639.802606908738, 5814.656572470931, 3201.9519707203945, 3774.3960966245268, 3796.6393192646656, 3796.7532110412735, 3862.729608927348, 4758.476567577494, 5391.32608423947, 5397.568194544506, 5574.996500629763, 5683.687397193194, 4314.007778211092, 5371.475406559067, 5441.707766801029, 5458.781648734006, 5585.301837270341, 5389.27244821944, 5450.454954504517, 5462.569156725219, 5480.0133927010675, 5507.807226747044, 4546.29532422475, 4733.5503127202865, 5029.220470827027, 5353.22378255143, 5418.674879507243, 5196.480351964773, 5293.735313234246, 5416.322959630046, 5418.42624753239, 5491.066518691752, 4517.435320584927, 4547.909041819211, 5471.945280547144, 5496.780128794803, 5497.917531236954], "value": 3894.419063714735, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [603.226816264267, 698.902153698482, 707.2465493212716, 775.689724110356, 780.2977320411633, 13638.522651707191, 14424.711505769877, 14662.853371466283, 14678.0, 14701.70596588069, 12181.634550963485, 12199.207051541644, 12288.938555307233, 13126.803097953538, 13365.131266467673, 666.0874601795547, 814.132948409943, 824.9793755156109, 825.7729124490753, 829.4028299624291, 680.9625470599115, 796.6574373019599, 827.1356432527992, 831.3183189784379, 833.9291160251386, 872.995635021824, 873.1574031060026, 873.401719821924, 873.7859224489977, 874.422880898607, 693.04013940692, 867.8828358171629, 870.1429092344036, 870.2602787630495, 871.8866547348836, 528.2973645052072, 621.2327775197627, 641.1376698340724, 645.2740666749906, 646.977355792545, 14034.017618766691, 14063.0, 14242.07426517273, 14266.286685665778, 14331.498397556139, 12943.482260709654, 13096.952243820471, 13209.075364724473, 13397.732045359038, 13414.597562073055, 11690.41547922609, 11932.940335298355, 11971.221870578385, 12141.93929030358, 12167.0, 4213.641840443566, 4285.400043993844, 4292.549282325342, 4351.8320226705655, 4477.2164871147415, 78435.6078219611, 80645.0, 81160.18839811644, 83178.0, 83442.58278708567, 10351.171906247482, 10530.420826854597, 10536.420496872746, 10555.313904596176, 10706.89293107059, 20172.09225584852, 20232.797672023386, 20403.4899127523, 20415.693764593543, 20435.693464598044, 7149.177844547839, 7329.633518324063, 7390.852182956418, 7542.6605802739, 7551.924480755232, 3925.430812532167, 3965.7620542767527, 3986.760794352348, 4018.3570628699267, 4021.6380525752825, 3268.950965735516, 3354.681305275995, 3394.4568868981337, 3399.2521645238007, 3409.318136372686, 602.9939700602938, 653.4543656047159, 663.2074670768482, 693.968771405288, 704.8766465868453, 568.8634727665413, 592.3720855892767, 611.810338794975, 655.374670816378, 668.204836244867, 8923.375363724477, 8930.285577153942, 8966.462012279153, 9169.908300917039, 9268.582913768896, 633.8276923995373, 640.3340525853154, 656.143732429175, 665.0755449924609, 668.5988406955885, 4115.464989551339, 4223.957760422418, 4248.129133527619, 4259.297215959394, 4555.40779698637, 74108.0, 90530.54734726416, 94699.52650236705, 103051.0, 108140.45929770458, 92228.42806367722, 93746.0625393751, 93953.0, 95913.0, 99522.50238748905, 75220.87168692474, 79484.6025769879, 80569.0, 80988.59505702434, 82277.58861205656, 8495.74512764618, 8619.827603447771, 8625.094365091574, 8645.80678370977, 8705.825883482168, 51387.743061284455, 52640.0, 53125.46874531205, 53810.730946345015, 57104.1434378493, 61368.69315653393, 73537.0, 79619.0, 82204.94487637856, 84456.15543844482, 13235.404406801525, 13333.333333333489, 13566.38951247216, 13584.456621735415, 13604.65988350299, 13233.867661323264, 13361.599152025456, 13863.930680346533, 13915.304234788422, 14169.22069286199, 12744.0, 12808.743825123633, 13429.731405372033, 13503.59489215325, 13760.0, 12894.806577901152, 13569.253691047088, 13868.0, 13959.651008724863, 14454.566363009126, 12199.634010979686, 13493.32533373347, 13641.658958526115, 13777.242251675852, 14095.718085638026, 10724.785504289714, 11007.394593297446, 11559.479823408135, 11645.126615503701, 12028.518859245532, 10554.525046372779, 11368.0, 11406.885931140914, 11548.133889958455, 11658.88341116578], "value": 7251.83830776236, "unit": "score"}], "name": "dromaeo_css", "value": 7092.442213109641, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 114.0, 114.0, 114.0, 113.0, 114.0, 114.0, 114.0, 114.0, 114.0], "value": 112.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 114.0, 139.0, 175.0, 111.0, 111.0, 111.0, 111.0, 141.0, 109.0], "value": 127.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 185.0, 183.0, 186.0, 185.0, 186.0, 183.0, 186.0, 182.0, 184.0], "value": 184.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 59.0, 59.0, 90.0, 67.0, 60.0, 59.0, 60.0, 83.0, 60.0], "value": 67.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 71.0, 127.0, 99.0, 54.0, 73.0, 123.0, 97.0, 98.0, 106.0], "value": 93.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 112.0, 133.0, 128.0, 125.0, 86.0, 89.0, 86.0, 90.0, 87.0], "value": 101.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 97.0, 97.0, 97.0, 126.0, 159.0, 151.0, 95.0, 114.0], "value": 112.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 80.0, 80.0, 81.0, 82.0, 86.0, 82.0, 81.0, 82.0], "value": 81.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 56.0, 55.0, 56.0, 58.0, 58.0, 58.0, 58.0, 58.0], "value": 56.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 77.0, 76.0, 73.0, 72.0, 72.0, 73.0, 72.0, 73.0], "value": 71.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 88.0, 100.0, 99.0, 83.0, 82.0, 83.0, 83.0, 92.0], "value": 87.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 153.0, 152.0, 142.0, 141.0, 150.0, 152.0, 152.0, 191.0, 143.0], "value": 151.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 165.0, 165.0, 166.0, 166.0, 163.0, 165.0, 166.0, 214.0, 219.0], "value": 175.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 68.0, 68.0, 66.0, 67.0, 67.0, 65.0, 68.0, 67.0], "value": 67.1, "unit": "score"}], "name": "kraken", "value": 1492.2, "alertThreshold": 2.0}]} 14:06:32 INFO - Return code: 0 14:06:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:06:32 INFO - # TBPL SUCCESS # 14:06:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:06:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:06:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:06:32 INFO - 2016-12-15 14:06:32.459 screenresolution[1923:14023] starting screenresolution argv=screenresolution get 14:06:32 INFO - 2016-12-15 14:06:32.462 screenresolution[1923:14023] Display 0: 1600x1200x32@60 14:06:32 INFO - 2016-12-15 14:06:32.468 screenresolution[1924:14026] starting screenresolution argv=screenresolution list 14:06:32 INFO - Available Modes on Display 0 14:06:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:06:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:06:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:06:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:06:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:06:32 INFO - Intel Iris: 14:06:32 INFO - Chipset Model: Intel Iris 14:06:32 INFO - Type: GPU 14:06:32 INFO - Bus: Built-In 14:06:32 INFO - VRAM (Dynamic, Max): 1536 MB 14:06:32 INFO - Vendor: Intel (0x8086) 14:06:32 INFO - Device ID: 0x0a2e 14:06:32 INFO - Revision ID: 0x0009 14:06:32 INFO - Displays: 14:06:32 INFO - Display: 14:06:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:06:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:06:32 INFO - Display Serial Number: 1600x1200 60 14:06:32 INFO - Main Display: Yes 14:06:32 INFO - Mirror: Off 14:06:32 INFO - Online: Yes 14:06:32 INFO - Rotation: Supported 14:06:32 INFO - Return code: 0 14:06:32 INFO - Running post-action listener: _resource_record_post_action 14:06:32 INFO - [mozharness: 2016-12-15 22:06:32.721532Z] Finished run-tests step (success) 14:06:32 INFO - Running post-run listener: _resource_record_post_run 14:06:32 INFO - Total resource usage - Wall time: 1214s; CPU: 53.0%; Read bytes: 782336; Write bytes: 651083264; Read time: 12; Write time: 1755 14:06:32 INFO - TinderboxPrint: CPU usage
53.3% 14:06:32 INFO - TinderboxPrint: I/O read bytes / time
782,336 / 12 14:06:32 INFO - TinderboxPrint: I/O write bytes / time
651,083,264 / 1,755 14:06:32 INFO - TinderboxPrint: CPU idle
2,268.4 (46.7%) 14:06:32 INFO - TinderboxPrint: CPU system
971.5 (20.0%) 14:06:32 INFO - TinderboxPrint: CPU user
1,617.9 (33.3%) 14:06:32 INFO - TinderboxPrint: Swap in / out
349,622,272 / 0 14:06:32 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 345625088; Write bytes: 345531904; Read time: 26939; Write time: 607 14:06:32 INFO - run-tests - Wall time: 1184s; CPU: 53.0%; Read bytes: 782336; Write bytes: 298502144; Read time: 12; Write time: 1119 14:06:33 INFO - Running post-run listener: _upload_blobber_files 14:06:33 INFO - Blob upload gear active. 14:06:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:06:33 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 14:06:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:06:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:06:33 INFO - (blobuploader) - INFO - Open directory for files ... 14:06:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:06:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:06:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:06:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:06:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:06:34 INFO - (blobuploader) - INFO - Done attempting. 14:06:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:06:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:06:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:06:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:06:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:06:35 INFO - (blobuploader) - INFO - Done attempting. 14:06:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:06:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:06:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:06:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:06:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:06:37 INFO - (blobuploader) - INFO - Done attempting. 14:06:37 INFO - (blobuploader) - INFO - Iteration through files over. 14:06:37 INFO - Return code: 0 14:06:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:06:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:06:37 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b507ba33f484fe3f79db8f0ff9a64e88375afad91de3bc281ba1d67d3a5e790d2d6b6077325517a122fff65955df0e2226672af87bb826113c9d849aeb5d88cb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d6d410ce5f9304d0335fc8b23470fe9cea6319588dbe4602c74c79a2f0badd82b9e849da81fa5209f7312aa69cb1c9d52c6a72d7f9e3af6a19991a21720799bf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1b17e5c5b6c67fa7d58cf44d7c7785ead2a8882b98d261ad98c583fbe18b6cd12f5deceb867c76f150381b4fcc427f96538bb04487d98aaf35976ce91aef7a1e"} 14:06:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:06:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:06:37 INFO - Contents: 14:06:37 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b507ba33f484fe3f79db8f0ff9a64e88375afad91de3bc281ba1d67d3a5e790d2d6b6077325517a122fff65955df0e2226672af87bb826113c9d849aeb5d88cb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d6d410ce5f9304d0335fc8b23470fe9cea6319588dbe4602c74c79a2f0badd82b9e849da81fa5209f7312aa69cb1c9d52c6a72d7f9e3af6a19991a21720799bf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1b17e5c5b6c67fa7d58cf44d7c7785ead2a8882b98d261ad98c583fbe18b6cd12f5deceb867c76f150381b4fcc427f96538bb04487d98aaf35976ce91aef7a1e"} 14:06:37 INFO - Running post-run listener: copy_logs_to_upload_dir 14:06:37 INFO - Copying logs to upload dir... 14:06:37 INFO - mkdir: /builds/slave/test/build/upload/logs 14:06:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.311631 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-12-15 14:06:37.931130) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-15 14:06:37.934296) ========= 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.bplbtlSfjJ/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.fgfEXIOfNl/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b507ba33f484fe3f79db8f0ff9a64e88375afad91de3bc281ba1d67d3a5e790d2d6b6077325517a122fff65955df0e2226672af87bb826113c9d849aeb5d88cb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d6d410ce5f9304d0335fc8b23470fe9cea6319588dbe4602c74c79a2f0badd82b9e849da81fa5209f7312aa69cb1c9d52c6a72d7f9e3af6a19991a21720799bf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1b17e5c5b6c67fa7d58cf44d7c7785ead2a8882b98d261ad98c583fbe18b6cd12f5deceb867c76f150381b4fcc427f96538bb04487d98aaf35976ce91aef7a1e"} build_url:https://queue.taskcluster.net/v1/task/M5CbLo8uSS-RC6QtkKztxw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011247 build_url: 'https://queue.taskcluster.net/v1/task/M5CbLo8uSS-RC6QtkKztxw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b507ba33f484fe3f79db8f0ff9a64e88375afad91de3bc281ba1d67d3a5e790d2d6b6077325517a122fff65955df0e2226672af87bb826113c9d849aeb5d88cb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d6d410ce5f9304d0335fc8b23470fe9cea6319588dbe4602c74c79a2f0badd82b9e849da81fa5209f7312aa69cb1c9d52c6a72d7f9e3af6a19991a21720799bf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1b17e5c5b6c67fa7d58cf44d7c7785ead2a8882b98d261ad98c583fbe18b6cd12f5deceb867c76f150381b4fcc427f96538bb04487d98aaf35976ce91aef7a1e"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-15 14:06:37.973537) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 14:06:37.973886) ========= 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.bplbtlSfjJ/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.fgfEXIOfNl/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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.004989 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 14:06:38.025741) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-15 14:06:38.026416) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-15 14:06:38.027300) ========= ========= Total master_lag: 0.20 =========