builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0151 starttime: 1491197514.69 results: success (0) buildid: 20170402213523 builduid: 28bc8d2985a14e0d97c040c624a03ef4 revision: b7220fe2a194b39e03e0e2156109fd418d317327 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-02 22:31:54.690695) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-02 22:31:54.691179) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-02 22:31:54.691463) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-02 22:31:54.718326) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-02 22:31:54.718674) ========= 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.5sSjplHfYa/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.0Q39PTyNsa/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-04-02 22:31:54-- 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% 168M=0s 2017-04-02 22:31:55 (168 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.394628 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-02 22:31:55.139952) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-02 22:31:55.140382) ========= 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.5sSjplHfYa/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.0Q39PTyNsa/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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.028644 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-02 22:31:55.191986) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-02 22:31:55.192410) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev b7220fe2a194b39e03e0e2156109fd418d317327 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev b7220fe2a194b39e03e0e2156109fd418d317327 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.5sSjplHfYa/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.0Q39PTyNsa/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-04-02 22:31:55,255 truncating revision to first 12 chars 2017-04-02 22:31:55,255 Setting DEBUG logging. 2017-04-02 22:31:55,255 attempt 1/10 2017-04-02 22:31:55,255 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/b7220fe2a194?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-02 22:31:56,379 unpacking tar archive at: mozilla-beta-b7220fe2a194/testing/mozharness/ program finished with exit code 0 elapsedTime=1.407702 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-02 22:31:56.623691) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-02 22:31:56.624046) ========= script_repo_revision: b7220fe2a194b39e03e0e2156109fd418d317327 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-02 22:31:56.624464) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-02 22:31:56.624728) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-02 22:31:56.644719) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2017-04-02 22:31:56.645193) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.5sSjplHfYa/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.0Q39PTyNsa/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 22:31:56 INFO - MultiFileLogger online at 20170402 22:31:56 in /builds/slave/test 22:31:56 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 22:31:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:31:56 INFO - {'append_to_log': False, 22:31:56 INFO - 'base_work_dir': '/builds/slave/test', 22:31:56 INFO - 'blob_upload_branch': 'Mozilla-Beta', 22:31:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:31:56 INFO - 'branch': 'Mozilla-Beta', 22:31:56 INFO - 'buildbot_json_path': 'buildprops.json', 22:31:56 INFO - 'config_files': ('talos/mac_config.py',), 22:31:56 INFO - 'default_actions': ('clobber', 22:31:56 INFO - 'read-buildbot-config', 22:31:56 INFO - 'download-and-extract', 22:31:56 INFO - 'populate-webroot', 22:31:56 INFO - 'create-virtualenv', 22:31:56 INFO - 'install', 22:31:56 INFO - 'run-tests'), 22:31:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:31:56 INFO - 'download_minidump_stackwalk': True, 22:31:56 INFO - 'download_symbols': 'ondemand', 22:31:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:31:56 INFO - 'tooltool.py': '/tools/tooltool.py', 22:31:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:31:56 INFO - '/tools/misc-python/virtualenv.py')}, 22:31:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:31:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:31:56 INFO - 'installer_path': 'installer.exe', 22:31:56 INFO - 'log_level': 'info', 22:31:56 INFO - 'log_name': 'talos', 22:31:56 INFO - 'log_to_console': True, 22:31:56 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:31:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:31:56 INFO - 'opt_config_files': (), 22:31:56 INFO - 'pip_index': False, 22:31:56 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:31:56 INFO - 'cmd': ('bash', 22:31:56 INFO - '-c', 22:31:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:31:56 INFO - 'enabled': True, 22:31:56 INFO - 'halt_on_failure': False, 22:31:56 INFO - 'name': 'check_screen_resolution'},), 22:31:56 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:31:56 INFO - 'cmd': ('bash', 22:31:56 INFO - '-c', 22:31:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:31:56 INFO - 'enabled': True, 22:31:56 INFO - 'halt_on_failure': False, 22:31:56 INFO - 'name': 'check_screen_resolution'},), 22:31:56 INFO - 'run_cmd_checks_enabled': True, 22:31:56 INFO - 'sps_profile': False, 22:31:56 INFO - 'sps_profile_interval': 0, 22:31:56 INFO - 'suite': 'dromaeojs-e10s', 22:31:56 INFO - 'system_bits': '32', 22:31:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:31:56 INFO - 'title': 't-yosemite-r7-0151', 22:31:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:31:56 INFO - 'use_talos_json': True, 22:31:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:31:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:31:56 INFO - 'work_dir': 'build'} 22:31:56 INFO - [mozharness: 2017-04-03 05:31:56.821712Z] Running clobber step. 22:31:56 INFO - Running pre-action listener: _resource_record_pre_action 22:31:56 INFO - Running main action method: clobber 22:31:56 INFO - rmtree: /builds/slave/test/build 22:31:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:31:59 INFO - Running post-action listener: _resource_record_post_action 22:31:59 INFO - [mozharness: 2017-04-03 05:31:59.399449Z] Finished clobber step (success) 22:31:59 INFO - [mozharness: 2017-04-03 05:31:59.399584Z] Running read-buildbot-config step. 22:31:59 INFO - Running pre-action listener: _resource_record_pre_action 22:31:59 INFO - Running main action method: read_buildbot_config 22:31:59 INFO - Using buildbot properties: 22:31:59 INFO - { 22:31:59 INFO - "project": "", 22:31:59 INFO - "product": "firefox", 22:31:59 INFO - "script_repo_revision": "production", 22:31:59 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 22:31:59 INFO - "repository": "", 22:31:59 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 22:31:59 INFO - "buildid": "20170402213523", 22:31:59 INFO - "pgo_build": "False", 22:31:59 INFO - "basedir": "/builds/slave/test", 22:31:59 INFO - "buildnumber": 26, 22:31:59 INFO - "slavename": "t-yosemite-r7-0151", 22:31:59 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 22:31:59 INFO - "platform": "yosemite_r7", 22:31:59 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 22:31:59 INFO - "branch": "mozilla-beta", 22:31:59 INFO - "repo_path": "releases/mozilla-beta", 22:31:59 INFO - "revision": "b7220fe2a194b39e03e0e2156109fd418d317327", 22:31:59 INFO - "stage_platform": "macosx64", 22:31:59 INFO - "builduid": "28bc8d2985a14e0d97c040c624a03ef4", 22:31:59 INFO - "slavebuilddir": "test" 22:31:59 INFO - } 22:31:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/OiK5arCoR0Cq1gy5tOSAvg/artifacts/public/build/firefox-53.0.en-US.mac.dmg. 22:31:59 INFO - Running post-action listener: _resource_record_post_action 22:31:59 INFO - [mozharness: 2017-04-03 05:31:59.402831Z] Finished read-buildbot-config step (success) 22:31:59 INFO - [mozharness: 2017-04-03 05:31:59.402944Z] Running download-and-extract step. 22:31:59 INFO - Running pre-action listener: _resource_record_pre_action 22:31:59 INFO - Running main action method: download_and_extract 22:31:59 INFO - mkdir: /builds/slave/test/build/tests 22:31:59 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')]} 22:31:59 INFO - trying https://queue.taskcluster.net/v1/task/OiK5arCoR0Cq1gy5tOSAvg/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json 22:31:59 INFO - Downloading https://queue.taskcluster.net/v1/task/OiK5arCoR0Cq1gy5tOSAvg/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json 22:31:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OiK5arCoR0Cq1gy5tOSAvg/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json'}, attempt #1 22:31:59 INFO - Downloaded 1165 bytes. 22:31:59 INFO - Reading from file /builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json 22:31:59 INFO - Using the following test package requirements: 22:31:59 INFO - {u'common': [u'firefox-53.0.en-US.mac.common.tests.zip'], 22:31:59 INFO - u'cppunittest': [u'firefox-53.0.en-US.mac.common.tests.zip', 22:31:59 INFO - u'firefox-53.0.en-US.mac.cppunittest.tests.zip'], 22:31:59 INFO - u'gtest': [u'firefox-53.0.en-US.mac.common.tests.zip', 22:31:59 INFO - u'firefox-53.0.en-US.mac.gtest.tests.zip'], 22:31:59 INFO - u'jittest': [u'firefox-53.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 22:31:59 INFO - u'mochitest': [u'firefox-53.0.en-US.mac.common.tests.zip', 22:31:59 INFO - u'firefox-53.0.en-US.mac.mochitest.tests.zip'], 22:31:59 INFO - u'mozbase': [u'firefox-53.0.en-US.mac.common.tests.zip'], 22:31:59 INFO - u'reftest': [u'firefox-53.0.en-US.mac.common.tests.zip', 22:31:59 INFO - u'firefox-53.0.en-US.mac.reftest.tests.zip'], 22:31:59 INFO - u'talos': [u'firefox-53.0.en-US.mac.common.tests.zip', 22:31:59 INFO - u'firefox-53.0.en-US.mac.talos.tests.zip'], 22:31:59 INFO - u'web-platform': [u'firefox-53.0.en-US.mac.common.tests.zip', 22:31:59 INFO - u'firefox-53.0.en-US.mac.web-platform.tests.zip'], 22:31:59 INFO - u'xpcshell': [u'firefox-53.0.en-US.mac.common.tests.zip', 22:31:59 INFO - u'firefox-53.0.en-US.mac.xpcshell.tests.zip']} 22:31:59 INFO - Downloading packages: [u'firefox-53.0.en-US.mac.common.tests.zip'] for test suite category: common 22:31:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OiK5arCoR0Cq1gy5tOSAvg/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip 22:31:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OiK5arCoR0Cq1gy5tOSAvg/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip'}, attempt #1 22:31:59 INFO - Fetch https://queue.taskcluster.net/v1/task/OiK5arCoR0Cq1gy5tOSAvg/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip into memory 22:32:00 INFO - Expected file size: 16109788 22:32:00 INFO - Obtained file size: 16109788 22:32:07 INFO - Downloading packages: [u'firefox-53.0.en-US.mac.common.tests.zip', u'firefox-53.0.en-US.mac.talos.tests.zip'] for test suite category: talos 22:32:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OiK5arCoR0Cq1gy5tOSAvg/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip 22:32:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OiK5arCoR0Cq1gy5tOSAvg/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip'}, attempt #1 22:32:07 INFO - Fetch https://queue.taskcluster.net/v1/task/OiK5arCoR0Cq1gy5tOSAvg/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip into memory 22:32:08 INFO - Expected file size: 16109788 22:32:08 INFO - Obtained file size: 16109788 22:32:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OiK5arCoR0Cq1gy5tOSAvg/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip 22:32:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OiK5arCoR0Cq1gy5tOSAvg/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip'}, attempt #1 22:32:14 INFO - Fetch https://queue.taskcluster.net/v1/task/OiK5arCoR0Cq1gy5tOSAvg/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip into memory 22:32:15 INFO - Expected file size: 14403663 22:32:15 INFO - Obtained file size: 14403663 22:32: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')]} 22:32:16 INFO - trying https://queue.taskcluster.net/v1/task/OiK5arCoR0Cq1gy5tOSAvg/artifacts/public/build/firefox-53.0.en-US.mac.dmg 22:32:16 INFO - Downloading https://queue.taskcluster.net/v1/task/OiK5arCoR0Cq1gy5tOSAvg/artifacts/public/build/firefox-53.0.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0.en-US.mac.dmg 22:32:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OiK5arCoR0Cq1gy5tOSAvg/artifacts/public/build/firefox-53.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0.en-US.mac.dmg'}, attempt #1 22:32:17 INFO - Downloaded 58726446 bytes. 22:32:17 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OiK5arCoR0Cq1gy5tOSAvg/artifacts/public/build/firefox-53.0.en-US.mac.dmg 22:32:17 INFO - mkdir: /builds/slave/test/properties 22:32:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:32:17 INFO - Writing to file /builds/slave/test/properties/build_url 22:32:17 INFO - Contents: 22:32:17 INFO - build_url:https://queue.taskcluster.net/v1/task/OiK5arCoR0Cq1gy5tOSAvg/artifacts/public/build/firefox-53.0.en-US.mac.dmg 22:32:18 INFO - Running post-action listener: _resource_record_post_action 22:32:18 INFO - Running post-action listener: set_extra_try_arguments 22:32:18 INFO - [mozharness: 2017-04-03 05:32:18.437890Z] Finished download-and-extract step (success) 22:32:18 INFO - [mozharness: 2017-04-03 05:32:18.438048Z] Running populate-webroot step. 22:32:18 INFO - Running pre-action listener: _resource_record_pre_action 22:32:18 INFO - Running main action method: populate_webroot 22:32:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:32:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:32:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:32:18 INFO - '--tppagecycles', 22:32:18 INFO - '7'], 22:32:18 INFO - 'tests': ['tsvgm']}}, 22:32:18 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:32:18 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:32:18 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:32:18 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:32:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:32:18 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:32:18 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 22:32:18 INFO - 'talos_options': ['--disable-e10s'], 22:32:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:32:18 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 22:32:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:32:18 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 22:32:18 INFO - 'talos_options': ['--disable-e10s'], 22:32:18 INFO - 'tests': ['damp', 'tps']}, 22:32:18 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 22:32:18 INFO - 'tests': ['damp', 'tps']}, 22:32:18 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:32:18 INFO - 'tests': ['dromaeo_dom']}, 22:32:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:32:18 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:32:18 INFO - 'tests': ['basic_compositor_video']}, 22:32:18 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:32:18 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:32:18 INFO - 'tests': ['a11yr', 22:32:18 INFO - 'ts_paint', 22:32:18 INFO - 'tpaint', 22:32:18 INFO - 'sessionrestore', 22:32:18 INFO - 'sessionrestore_no_auto_restore', 22:32:18 INFO - 'tabpaint']}, 22:32:18 INFO - 'other-e10s': {'tests': ['a11yr', 22:32:18 INFO - 'ts_paint', 22:32:18 INFO - 'tpaint', 22:32:18 INFO - 'sessionrestore', 22:32:18 INFO - 'sessionrestore_no_auto_restore', 22:32:18 INFO - 'tabpaint']}, 22:32:18 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:32:18 INFO - 'ts_paint', 22:32:18 INFO - 'tpaint', 22:32:18 INFO - 'sessionrestore', 22:32:18 INFO - 'sessionrestore_no_auto_restore', 22:32:18 INFO - 'tabpaint']}, 22:32:18 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:32:18 INFO - 'ts_paint', 22:32:18 INFO - 'tpaint', 22:32:18 INFO - 'sessionrestore', 22:32:18 INFO - 'sessionrestore_no_auto_restore', 22:32:18 INFO - 'tabpaint']}, 22:32:18 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:32:18 INFO - 'tests': ['a11yr', 22:32:18 INFO - 'ts_paint', 22:32:18 INFO - 'tpaint', 22:32:18 INFO - 'sessionrestore', 22:32:18 INFO - 'sessionrestore_no_auto_restore', 22:32:18 INFO - 'tabpaint']}, 22:32:18 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:32:18 INFO - 'tests': ['a11yr', 22:32:18 INFO - 'ts_paint', 22:32:18 INFO - 'tpaint', 22:32:18 INFO - 'sessionrestore', 22:32:18 INFO - 'sessionrestore_no_auto_restore', 22:32:18 INFO - 'tabpaint']}, 22:32:18 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:32:18 INFO - 'tests': ['tsvgx', 22:32:18 INFO - 'tsvgr_opacity', 22:32:18 INFO - 'tart', 22:32:18 INFO - 'tscrollx', 22:32:18 INFO - 'cart', 22:32:18 INFO - 'tsvg_static']}, 22:32:18 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:32:18 INFO - 'tsvgr_opacity', 22:32:18 INFO - 'tart', 22:32:18 INFO - 'tscrollx', 22:32:18 INFO - 'cart', 22:32:18 INFO - 'tsvg_static']}, 22:32:18 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 22:32:18 INFO - 'talos_options': ['--disable-e10s'], 22:32:18 INFO - 'tests': ['tp5o']}, 22:32:18 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 22:32:18 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 22:32:18 INFO - 'talos_options': ['--disable-e10s', 22:32:18 INFO - '--xperf_path', 22:32:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:32:18 INFO - 'tests': ['tp5n']}, 22:32:18 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 22:32:18 INFO - 'talos_options': ['--xperf_path', 22:32:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:32:18 INFO - 'tests': ['tp5n']}}, 22:32:18 INFO - 'talos.zip': {'path': '', 22:32:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:32:18 INFO - Running post-action listener: _resource_record_post_action 22:32:18 INFO - [mozharness: 2017-04-03 05:32:18.456438Z] Finished populate-webroot step (success) 22:32:18 INFO - [mozharness: 2017-04-03 05:32:18.456557Z] Running create-virtualenv step. 22:32:18 INFO - Running pre-action listener: _resource_record_pre_action 22:32:18 INFO - Running main action method: create_virtualenv 22:32:18 INFO - Creating virtualenv /builds/slave/test/build/venv 22:32:18 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 22:32:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:32:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:32:18 INFO - Using real prefix '/tools/python27' 22:32:18 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:32:19 INFO - Installing distribute.............................................................................................................................................................................................done. 22:32:22 INFO - Installing pip.................done. 22:32:22 INFO - Return code: 0 22:32:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 22:32:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 22:32:22 INFO - Reading from file tmpfile_stdout 22:32:22 INFO - Output received: 22:32:22 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) 22:32:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:32: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')]} 22:32:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:32:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:32: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')]} 22:32:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:32:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:32:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109184b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109187768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2f2d66ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f62d30>, '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 0x108e89db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f57be8>, '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.5sSjplHfYa/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.0Q39PTyNsa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 22:32:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:32:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:32:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5sSjplHfYa/Render', 22:32:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:32:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:32:22 INFO - 'HOME': '/Users/cltbld', 22:32:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:32:22 INFO - 'LOGNAME': 'cltbld', 22:32:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:32:22 INFO - 'MOZ_NO_REMOTE': '1', 22:32:22 INFO - 'NO_EM_RESTART': '1', 22:32:22 INFO - 'PAGER': '/bin/cat', 22:32:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:32:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:32:22 INFO - 'PWD': '/builds/slave/test', 22:32:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:32:22 INFO - 'SHELL': '/bin/bash', 22:32:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0Q39PTyNsa/Listeners', 22:32:22 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 22:32:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:32:22 INFO - 'USER': 'cltbld', 22:32:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:32:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:32:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:32:22 INFO - 'XPC_FLAGS': '0x0', 22:32:22 INFO - 'XPC_SERVICE_NAME': '0', 22:32:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:32:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:32:22 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 22:32:22 INFO - Cleaning up... 22:32:22 INFO - Return code: 0 22:32:22 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:32: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')]} 22:32:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:32:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:32: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')]} 22:32:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:32:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:32:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109184b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109187768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2f2d66ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f62d30>, '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 0x108e89db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f57be8>, '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.5sSjplHfYa/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.0Q39PTyNsa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 22:32:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 22:32:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 22:32:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5sSjplHfYa/Render', 22:32:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:32:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:32:22 INFO - 'HOME': '/Users/cltbld', 22:32:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:32:22 INFO - 'LOGNAME': 'cltbld', 22:32:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:32:22 INFO - 'MOZ_NO_REMOTE': '1', 22:32:22 INFO - 'NO_EM_RESTART': '1', 22:32:22 INFO - 'PAGER': '/bin/cat', 22:32:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:32:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:32:22 INFO - 'PWD': '/builds/slave/test', 22:32:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:32:22 INFO - 'SHELL': '/bin/bash', 22:32:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0Q39PTyNsa/Listeners', 22:32:22 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 22:32:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:32:22 INFO - 'USER': 'cltbld', 22:32:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:32:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:32:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:32:22 INFO - 'XPC_FLAGS': '0x0', 22:32:22 INFO - 'XPC_SERVICE_NAME': '0', 22:32:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:32:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:32:22 INFO - Downloading/unpacking psutil>=3.1.1 22:32:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:32:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:32:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:32:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:32:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:32:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:32:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:32:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:32:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:32:26 INFO - Installing collected packages: psutil 22:32:26 INFO - Running setup.py install for psutil 22:32:26 INFO - building 'psutil._psutil_osx' extension 22:32:26 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 22:32:27 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 22:32:27 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 22:32:27 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 22:32:27 INFO - building 'psutil._psutil_posix' extension 22:32:27 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 22:32:27 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:32:27 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:32:27 INFO - ^ 22:32:27 INFO - 1 warning generated. 22:32:27 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 22:32:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:32:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:32:27 INFO - Successfully installed psutil 22:32:27 INFO - Cleaning up... 22:32:27 INFO - Return code: 0 22:32:27 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:32:27 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')]} 22:32:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:32:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:32:27 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')]} 22:32:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:32:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:32:27 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 0x109184b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109187768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2f2d66ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f62d30>, '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 0x108e89db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f57be8>, '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.5sSjplHfYa/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.0Q39PTyNsa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 22:32:27 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 22:32:27 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 22:32:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5sSjplHfYa/Render', 22:32:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:32:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:32:27 INFO - 'HOME': '/Users/cltbld', 22:32:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:32:27 INFO - 'LOGNAME': 'cltbld', 22:32:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:32:27 INFO - 'MOZ_NO_REMOTE': '1', 22:32:27 INFO - 'NO_EM_RESTART': '1', 22:32:27 INFO - 'PAGER': '/bin/cat', 22:32:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:32:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:32:27 INFO - 'PWD': '/builds/slave/test', 22:32:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:32:27 INFO - 'SHELL': '/bin/bash', 22:32:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0Q39PTyNsa/Listeners', 22:32:27 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 22:32:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:32:27 INFO - 'USER': 'cltbld', 22:32:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:32:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:32:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:32:27 INFO - 'XPC_FLAGS': '0x0', 22:32:27 INFO - 'XPC_SERVICE_NAME': '0', 22:32:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:32:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:32:27 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:32:27 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 22:32:27 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 22:32:27 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 22:32:27 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 22:32:27 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 22:32:27 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 22:32:30 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:32:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:32:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:32:30 INFO - Installing collected packages: mozsystemmonitor 22:32:30 INFO - Running setup.py install for mozsystemmonitor 22:32:30 INFO - Successfully installed mozsystemmonitor 22:32:30 INFO - Cleaning up... 22:32:30 INFO - Return code: 0 22:32:30 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:32:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:32:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:32:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:32:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:32:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:32:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:32:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109184b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109187768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2f2d66ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f62d30>, '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 0x108e89db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f57be8>, '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.5sSjplHfYa/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.0Q39PTyNsa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 22:32:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 22:32:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 22:32:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5sSjplHfYa/Render', 22:32:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:32:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:32:30 INFO - 'HOME': '/Users/cltbld', 22:32:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:32:30 INFO - 'LOGNAME': 'cltbld', 22:32:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:32:30 INFO - 'MOZ_NO_REMOTE': '1', 22:32:30 INFO - 'NO_EM_RESTART': '1', 22:32:30 INFO - 'PAGER': '/bin/cat', 22:32:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:32:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:32:30 INFO - 'PWD': '/builds/slave/test', 22:32:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:32:30 INFO - 'SHELL': '/bin/bash', 22:32:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0Q39PTyNsa/Listeners', 22:32:30 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 22:32:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:32:30 INFO - 'USER': 'cltbld', 22:32:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:32:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:32:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:32:30 INFO - 'XPC_FLAGS': '0x0', 22:32:30 INFO - 'XPC_SERVICE_NAME': '0', 22:32:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:32:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:32:31 INFO - Downloading/unpacking jsonschema==2.5.1 22:32:31 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 22:32:31 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 22:32:31 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 22:32:31 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 22:32:31 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 22:32:31 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 22:32:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:32:33 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:32:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:32:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:32:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:32:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:32:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:32:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:32:34 INFO - Downloading functools32-3.2.3-2.tar.gz 22:32:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:32:34 INFO - warning: no files found matching '*.txt' 22:32:34 INFO - no previously-included directories found matching 'build' 22:32:34 INFO - no previously-included directories found matching 'dist' 22:32:34 INFO - no previously-included directories found matching '.git*' 22:32:34 INFO - Installing collected packages: jsonschema, functools32 22:32:34 INFO - Running setup.py install for functools32 22:32:34 INFO - warning: no files found matching '*.txt' 22:32:34 INFO - no previously-included directories found matching 'build' 22:32:34 INFO - no previously-included directories found matching 'dist' 22:32:34 INFO - no previously-included directories found matching '.git*' 22:32:34 INFO - Successfully installed jsonschema functools32 22:32:34 INFO - Cleaning up... 22:32:34 INFO - Return code: 0 22:32:34 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:32:34 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')]} 22:32:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:32:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:32:34 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')]} 22:32:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:32:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:32:34 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 0x109184b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109187768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2f2d66ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f62d30>, '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 0x108e89db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f57be8>, '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.5sSjplHfYa/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.0Q39PTyNsa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 22:32:34 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 22:32:34 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 22:32:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5sSjplHfYa/Render', 22:32:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:32:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:32:34 INFO - 'HOME': '/Users/cltbld', 22:32:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:32:34 INFO - 'LOGNAME': 'cltbld', 22:32:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:32:34 INFO - 'MOZ_NO_REMOTE': '1', 22:32:34 INFO - 'NO_EM_RESTART': '1', 22:32:34 INFO - 'PAGER': '/bin/cat', 22:32:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:32:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:32:34 INFO - 'PWD': '/builds/slave/test', 22:32:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:32:34 INFO - 'SHELL': '/bin/bash', 22:32:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0Q39PTyNsa/Listeners', 22:32:34 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 22:32:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:32:34 INFO - 'USER': 'cltbld', 22:32:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:32:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:32:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:32:34 INFO - 'XPC_FLAGS': '0x0', 22:32:34 INFO - 'XPC_SERVICE_NAME': '0', 22:32:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:32:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:32:34 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:32:34 INFO - Cleaning up... 22:32:34 INFO - Return code: 0 22:32:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:32:34 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')]} 22:32:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:32:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:32:34 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')]} 22:32:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:32:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:32:34 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 0x109184b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109187768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2f2d66ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f62d30>, '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 0x108e89db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f57be8>, '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.5sSjplHfYa/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.0Q39PTyNsa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 22:32:34 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 22:32:34 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 22:32:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5sSjplHfYa/Render', 22:32:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:32:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:32:34 INFO - 'HOME': '/Users/cltbld', 22:32:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:32:34 INFO - 'LOGNAME': 'cltbld', 22:32:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:32:34 INFO - 'MOZ_NO_REMOTE': '1', 22:32:34 INFO - 'NO_EM_RESTART': '1', 22:32:34 INFO - 'PAGER': '/bin/cat', 22:32:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:32:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:32:34 INFO - 'PWD': '/builds/slave/test', 22:32:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:32:34 INFO - 'SHELL': '/bin/bash', 22:32:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0Q39PTyNsa/Listeners', 22:32:34 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 22:32:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:32:34 INFO - 'USER': 'cltbld', 22:32:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:32:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:32:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:32:34 INFO - 'XPC_FLAGS': '0x0', 22:32:34 INFO - 'XPC_SERVICE_NAME': '0', 22:32:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:32:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:32:34 INFO - Downloading/unpacking blobuploader==1.2.4 22:32:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:32:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:32:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:32:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:32:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:32:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:32:37 INFO - Downloading blobuploader-1.2.4.tar.gz 22:32:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:32:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:32:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:32:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:32:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:32:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:32:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:32:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:32:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:32:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:32:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:32:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:32:38 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 22:32:38 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 22:32:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:32:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:32:38 INFO - Downloading docopt-0.6.1.tar.gz 22:32:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:32:38 INFO - Installing collected packages: blobuploader, requests, docopt 22:32:38 INFO - Running setup.py install for blobuploader 22:32:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:32:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:32:39 INFO - Running setup.py install for requests 22:32:39 INFO - Running setup.py install for docopt 22:32:39 INFO - Successfully installed blobuploader requests docopt 22:32:39 INFO - Cleaning up... 22:32:39 INFO - Return code: 0 22:32:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:32:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:32:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:32:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:32:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:32:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:32:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:32:39 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 0x109184b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109187768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2f2d66ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f62d30>, '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 0x108e89db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f57be8>, '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.5sSjplHfYa/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.0Q39PTyNsa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 22:32:39 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 22:32:39 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 22:32:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5sSjplHfYa/Render', 22:32:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:32:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:32:39 INFO - 'HOME': '/Users/cltbld', 22:32:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:32:39 INFO - 'LOGNAME': 'cltbld', 22:32:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:32:39 INFO - 'MOZ_NO_REMOTE': '1', 22:32:39 INFO - 'NO_EM_RESTART': '1', 22:32:39 INFO - 'PAGER': '/bin/cat', 22:32:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:32:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:32:39 INFO - 'PWD': '/builds/slave/test', 22:32:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:32:39 INFO - 'SHELL': '/bin/bash', 22:32:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0Q39PTyNsa/Listeners', 22:32:39 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 22:32:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:32:39 INFO - 'USER': 'cltbld', 22:32:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:32:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:32:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:32:39 INFO - 'XPC_FLAGS': '0x0', 22:32:39 INFO - 'XPC_SERVICE_NAME': '0', 22:32:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:32:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:32:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:32:40 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-6hLr2f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:32:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:32:40 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-e8lVKa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:32:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:32:40 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-z68Pkq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:32:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:32:40 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-0BTnlD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:32:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:32:40 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-yQxvjX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:32:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:32:40 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-4YXy3L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:32:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:32:40 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-htZghC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:32:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:32:40 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-vfRsYg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:32:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:32:40 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-7jERGF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:32:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:32:41 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-oF4NXs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:32:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:32:41 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-6MFALr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:32:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:32:41 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-d17z7T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:32:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:32:41 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-Ssq_th-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:32:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:32:41 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-V5Mlo4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:32:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:32:41 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-sw3Qru-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:32:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:32:41 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-l36W8F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:32:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:32:41 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-5u5bQN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:32:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:32:41 INFO - Running setup.py install for manifestparser 22:32:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:32:42 INFO - Running setup.py install for mozcrash 22:32:42 INFO - Running setup.py install for mozdebug 22:32:42 INFO - Running setup.py install for mozdevice 22:32:42 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:32:42 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:32:42 INFO - Running setup.py install for mozfile 22:32:42 INFO - Running setup.py install for mozhttpd 22:32:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:32:42 INFO - Running setup.py install for mozinfo 22:32:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:32:42 INFO - Running setup.py install for mozInstall 22:32:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:32:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:32:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:32:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:32:43 INFO - Running setup.py install for mozleak 22:32:43 INFO - Running setup.py install for mozlog 22:32:43 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:32:43 INFO - Running setup.py install for moznetwork 22:32:43 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:32:43 INFO - Running setup.py install for mozprocess 22:32:43 INFO - Running setup.py install for mozprofile 22:32:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:32:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:32:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:32:43 INFO - Running setup.py install for mozrunner 22:32:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:32:44 INFO - Running setup.py install for mozscreenshot 22:32:44 INFO - Running setup.py install for moztest 22:32:44 INFO - Running setup.py install for mozversion 22:32:44 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:32:44 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:32:44 INFO - Cleaning up... 22:32:44 INFO - Return code: 0 22:32:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:32:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:32:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:32:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:32:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:32:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:32:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:32:44 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 0x109184b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109187768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2f2d66ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f62d30>, '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 0x108e89db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f57be8>, '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.5sSjplHfYa/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.0Q39PTyNsa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 22:32:44 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 22:32:44 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 22:32:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5sSjplHfYa/Render', 22:32:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:32:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:32:44 INFO - 'HOME': '/Users/cltbld', 22:32:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:32:44 INFO - 'LOGNAME': 'cltbld', 22:32:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:32:44 INFO - 'MOZ_NO_REMOTE': '1', 22:32:44 INFO - 'NO_EM_RESTART': '1', 22:32:44 INFO - 'PAGER': '/bin/cat', 22:32:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:32:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:32:44 INFO - 'PWD': '/builds/slave/test', 22:32:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:32:44 INFO - 'SHELL': '/bin/bash', 22:32:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0Q39PTyNsa/Listeners', 22:32:44 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 22:32:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:32:44 INFO - 'USER': 'cltbld', 22:32:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:32:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:32:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:32:44 INFO - 'XPC_FLAGS': '0x0', 22:32:44 INFO - 'XPC_SERVICE_NAME': '0', 22:32:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:32:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:32:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:32:44 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-E_Tabq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:32:44 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)) 22:32:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:32:44 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-VwFU1a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:32:44 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)) 22:32:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:32:44 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-Y3sW1g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:32:45 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)) 22:32:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:32:45 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-WCMv8Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:32:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:32:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:32:45 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-jY0rSZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:32:45 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)) 22:32:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:32:45 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-Uc1W9E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:32:45 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)) 22:32:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:32:45 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-OiWoPs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:32:45 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)) 22:32:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:32:45 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-IvfjQF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:32:45 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)) 22:32:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:32:45 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-9iIa29-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:32:45 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)) 22:32:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:32:45 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-36EZQM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:32:45 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)) 22:32:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:32:45 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-e608OX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:32:45 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)) 22:32:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:32:45 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-OsaoC_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:32:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 22:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:32:46 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-ktM0VM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:32:46 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)) 22:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:32:46 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-f3efkI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:32:46 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)) 22:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:32:46 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-dYSSYv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:32:46 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)) 22:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:32:46 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-cGP6ei-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:32:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 22:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:32:46 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-ckQlWg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:32:46 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)) 22:32:46 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)) 22:32:46 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)) 22:32:46 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)) 22:32:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:32:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:32:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:32:46 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 22:32:46 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 22:32:46 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 22:32:46 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 22:32:46 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 22:32:46 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 22:32:49 INFO - Downloading blessings-1.6.tar.gz 22:32:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:32:49 INFO - Installing collected packages: blessings 22:32:49 INFO - Running setup.py install for blessings 22:32:49 INFO - Successfully installed blessings 22:32:49 INFO - Cleaning up... 22:32:49 INFO - Return code: 0 22:32:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:32:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:32:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:32:49 INFO - Reading from file tmpfile_stdout 22:32:49 INFO - Current package versions: 22:32:49 INFO - blessings == 1.6 22:32:49 INFO - blobuploader == 1.2.4 22:32:49 INFO - docopt == 0.6.1 22:32:49 INFO - functools32 == 3.2.3-2 22:32:49 INFO - jsonschema == 2.5.1 22:32:49 INFO - manifestparser == 1.1 22:32:49 INFO - mozInstall == 1.12 22:32:49 INFO - mozcrash == 1.0 22:32:49 INFO - mozdebug == 0.1 22:32:49 INFO - mozdevice == 0.49 22:32:49 INFO - mozfile == 1.2 22:32:49 INFO - mozhttpd == 0.7 22:32:49 INFO - mozinfo == 0.9 22:32:49 INFO - mozleak == 0.1 22:32:49 INFO - mozlog == 3.4 22:32:49 INFO - moznetwork == 0.27 22:32:49 INFO - mozprocess == 0.24 22:32:49 INFO - mozprofile == 0.28 22:32:49 INFO - mozrunner == 6.13 22:32:49 INFO - mozscreenshot == 0.1 22:32:49 INFO - mozsystemmonitor == 0.3 22:32:49 INFO - moztest == 0.8 22:32:49 INFO - mozversion == 1.4 22:32:49 INFO - psutil == 3.1.1 22:32:49 INFO - requests == 1.2.3 22:32:49 INFO - wsgiref == 0.1.2 22:32:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:32: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')]} 22:32:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:32:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:32: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')]} 22:32:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:32:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:32:49 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 0x109184b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109187768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2f2d66ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f62d30>, '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 0x108e89db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f57be8>, '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.5sSjplHfYa/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.0Q39PTyNsa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 22:32:49 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 22:32:49 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 22:32:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5sSjplHfYa/Render', 22:32:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:32:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:32:49 INFO - 'HOME': '/Users/cltbld', 22:32:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:32:49 INFO - 'LOGNAME': 'cltbld', 22:32:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:32:49 INFO - 'MOZ_NO_REMOTE': '1', 22:32:49 INFO - 'NO_EM_RESTART': '1', 22:32:49 INFO - 'PAGER': '/bin/cat', 22:32:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:32:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:32:49 INFO - 'PWD': '/builds/slave/test', 22:32:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:32:49 INFO - 'SHELL': '/bin/bash', 22:32:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0Q39PTyNsa/Listeners', 22:32:49 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 22:32:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:32:49 INFO - 'USER': 'cltbld', 22:32:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:32:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:32:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:32:49 INFO - 'XPC_FLAGS': '0x0', 22:32:49 INFO - 'XPC_SERVICE_NAME': '0', 22:32:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:32:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:32:50 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)) 22:32:50 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)) 22:32:50 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)) 22:32:50 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)) 22:32:50 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)) 22:32:50 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)) 22:32:50 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)) 22:32:50 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)) 22:32:50 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)) 22:32:50 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)) 22:32:50 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)) 22:32:50 INFO - Cleaning up... 22:32:50 INFO - Return code: 0 22:32:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:32:50 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')]} 22:32:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:32:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:32:50 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')]} 22:32:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:32:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:32:50 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 0x109184b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109187768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2f2d66ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f62d30>, '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 0x108e89db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f57be8>, '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.5sSjplHfYa/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.0Q39PTyNsa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 22:32:50 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 22:32:50 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 22:32:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5sSjplHfYa/Render', 22:32:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:32:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:32:50 INFO - 'HOME': '/Users/cltbld', 22:32:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:32:50 INFO - 'LOGNAME': 'cltbld', 22:32:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:32:50 INFO - 'MOZ_NO_REMOTE': '1', 22:32:50 INFO - 'NO_EM_RESTART': '1', 22:32:50 INFO - 'PAGER': '/bin/cat', 22:32:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:32:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:32:50 INFO - 'PWD': '/builds/slave/test', 22:32:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:32:50 INFO - 'SHELL': '/bin/bash', 22:32:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0Q39PTyNsa/Listeners', 22:32:50 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 22:32:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:32:50 INFO - 'USER': 'cltbld', 22:32:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:32:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:32:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:32:50 INFO - 'XPC_FLAGS': '0x0', 22:32:50 INFO - 'XPC_SERVICE_NAME': '0', 22:32:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:32:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:32:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:32:50 INFO - Cleaning up... 22:32:50 INFO - Return code: 0 22:32:50 INFO - Running post-action listener: _resource_record_post_action 22:32:50 INFO - Running post-action listener: _start_resource_monitoring 22:32:50 INFO - Starting resource monitoring. 22:32:50 INFO - [mozharness: 2017-04-03 05:32:50.416927Z] Finished create-virtualenv step (success) 22:32:50 INFO - [mozharness: 2017-04-03 05:32:50.417830Z] Running install step. 22:32:50 INFO - Running pre-action listener: _resource_record_pre_action 22:32:50 INFO - Running main action method: install 22:32:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:32:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:32:50 INFO - Reading from file tmpfile_stdout 22:32:50 INFO - Detecting whether we're running mozinstall >=1.0... 22:32:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:32:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:32:50 INFO - Reading from file tmpfile_stdout 22:32:50 INFO - Output received: 22:32:50 INFO - Usage: mozinstall [options] installer 22:32:50 INFO - Options: 22:32:50 INFO - -h, --help show this help message and exit 22:32:50 INFO - -d DEST, --destination=DEST 22:32:50 INFO - Directory to install application into. [default: 22:32:50 INFO - "/builds/slave/test"] 22:32:50 INFO - --app=APP Application being installed. [default: firefox] 22:32:50 INFO - mkdir: /builds/slave/test/build/application 22:32:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 22:32:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0.en-US.mac.dmg --destination /builds/slave/test/build/application 22:33:05 INFO - Reading from file tmpfile_stdout 22:33:05 INFO - Output received: 22:33:05 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 22:33:05 INFO - Running post-action listener: _resource_record_post_action 22:33:05 INFO - [mozharness: 2017-04-03 05:33:05.241559Z] Finished install step (success) 22:33:05 INFO - [mozharness: 2017-04-03 05:33:05.241714Z] Running run-tests step. 22:33:05 INFO - Running pre-action listener: _resource_record_pre_action 22:33:05 INFO - Running main action method: run_tests 22:33:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:33:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:33:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:33:05 INFO - 2017-04-02 22:33:05.256 screenresolution[1628:7376] starting screenresolution argv=screenresolution get 22:33:05 INFO - 2017-04-02 22:33:05.258 screenresolution[1628:7376] Display 0: 1600x1200x32@60 22:33:05 INFO - 2017-04-02 22:33:05.264 screenresolution[1629:7378] starting screenresolution argv=screenresolution list 22:33:05 INFO - Available Modes on Display 0 22:33:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:33:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:33:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:33:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:33:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:33:05 INFO - Intel Iris: 22:33:05 INFO - Chipset Model: Intel Iris 22:33:05 INFO - Type: GPU 22:33:05 INFO - Bus: Built-In 22:33:05 INFO - VRAM (Dynamic, Max): 1536 MB 22:33:05 INFO - Vendor: Intel (0x8086) 22:33:05 INFO - Device ID: 0x0a2e 22:33:05 INFO - Revision ID: 0x0009 22:33:05 INFO - Displays: 22:33:05 INFO - Display: 22:33:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:33:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:33:05 INFO - Display Serial Number: 1600x1200 60 22:33:05 INFO - Main Display: Yes 22:33:05 INFO - Mirror: Off 22:33:05 INFO - Online: Yes 22:33:05 INFO - Rotation: Supported 22:33:05 INFO - Return code: 0 22:33:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:33:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:33:05 INFO - Python 2.7.3 22:33:05 INFO - Return code: 0 22:33:05 INFO - grabbing minidump binary from tooltool 22:33: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')]} 22:33:05 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 0x108f62d30>, '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 0x108e89db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f57be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:33:05 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 22:33:05 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 22:33:05 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 22:33:05 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:33:05 INFO - Return code: 0 22:33:05 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:33:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:33:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:33:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:33:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:33:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OiK5arCoR0Cq1gy5tOSAvg/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0151', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 22:33:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/OiK5arCoR0Cq1gy5tOSAvg/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0151 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 22:33:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5sSjplHfYa/Render', 22:33:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:33:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:33:05 INFO - 'HOME': '/Users/cltbld', 22:33:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:33:05 INFO - 'LOGNAME': 'cltbld', 22:33:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:33:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:33:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:33:05 INFO - 'MOZ_NO_REMOTE': '1', 22:33:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:33:05 INFO - 'NO_EM_RESTART': '1', 22:33:05 INFO - 'PAGER': '/bin/cat', 22:33:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:33:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:33:05 INFO - 'PWD': '/builds/slave/test', 22:33:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:33:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:33:05 INFO - 'SHELL': '/bin/bash', 22:33:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0Q39PTyNsa/Listeners', 22:33:05 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 22:33:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:33:05 INFO - 'USER': 'cltbld', 22:33:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:33:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:33:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:33:05 INFO - 'XPC_FLAGS': '0x0', 22:33:05 INFO - 'XPC_SERVICE_NAME': '0', 22:33:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:33:05 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OiK5arCoR0Cq1gy5tOSAvg/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0151', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 22:33:05 INFO - mozversion application_buildid: 20170402213523 22:33:05 INFO - mozversion application_changeset: b7220fe2a194b39e03e0e2156109fd418d317327 22:33:05 INFO - mozversion application_display_name: Firefox 22:33:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:33:05 INFO - mozversion application_name: Firefox 22:33:05 INFO - mozversion application_remotingname: firefox 22:33:05 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 22:33:05 INFO - mozversion application_vendor: Mozilla 22:33:05 INFO - mozversion application_version: 53.0 22:33:05 INFO - mozversion platform_buildid: 20170402213523 22:33:05 INFO - mozversion platform_changeset: b7220fe2a194b39e03e0e2156109fd418d317327 22:33:05 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 22:33:05 INFO - mozversion platform_version: 53.0 22:33:05 INFO - using testdate: 1491197585 22:33:05 INFO - actual date: 1491197585 22:33:05 INFO - starting webserver on 'localhost:49242' 22:33:05 INFO - SUITE-START | Running 2 tests 22:33:05 INFO - TEST-START | dromaeo_css 22:33:05 INFO - operating with platform_type : mac_ 22:33:05 INFO - Initialising browser for dromaeo_css test... 22:33:05 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/tmpefxne9/profile http://localhost:49242/getInfo.html) 22:33:08 INFO - PROCESS | 1639 | 2017-04-02 22:33:08.718 plugin-container[1643:7556] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b3f, name = 'com.apple.tsm.portname' 22:33:08 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:33:08 INFO - PROCESS | 1639 | 2017-04-02 22:33:08.719 plugin-container[1643:7556] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910b, name = 'com.apple.CFPasteboardClient' 22:33:08 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:33:08 INFO - PROCESS | 1639 | 2017-04-02 22:33:08.719 plugin-container[1643:7556] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:33:08 INFO - PROCESS | 1639 | __metrics Screen width/height:1600/1200 22:33:08 INFO - PROCESS | 1639 | colorDepth:24 22:33:08 INFO - PROCESS | 1639 | Browser inner width/height: 1024/689 22:33:08 INFO - PROCESS | 1639 | __metrics 22:33:08 ERROR - PROCESS | 1639 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 22:33:09 INFO - TEST-INFO | 1639: exit 0 22:33:09 INFO - Browser initialized. 22:33:09 INFO - Running cycle 1/1 for dromaeo_css test... 22:33:09 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/tmpefxne9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:33:11 INFO - PROCESS | 1644 | 2017-04-02 22:33:11.759 plugin-container[1645:7696] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 22:33:11 INFO - PROCESS | 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:33:11 INFO - PROCESS | 1644 | 2017-04-02 22:33:11.760 plugin-container[1645:7696] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x890f, name = 'com.apple.CFPasteboardClient' 22:33:11 INFO - PROCESS | 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:33:11 INFO - PROCESS | 1644 | 2017-04-02 22:33:11.760 plugin-container[1645:7696] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:33:21 INFO - PROCESS | 1644 | RSS: Main: 173355008 22:33:21 INFO - PROCESS | 1644 | 22:36:23 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 22:36:24 INFO - PROCESS | 1644 | RSS: Main: 190873600 22:36:24 INFO - PROCESS | 1644 | 22:39:27 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 22:39:27 INFO - PROCESS | 1644 | RSS: Main: 201408512 22:39:27 INFO - PROCESS | 1644 | 22:42:29 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 22:42:30 INFO - PROCESS | 1644 | RSS: Main: 203177984 22:42:30 INFO - PROCESS | 1644 | 22:45:32 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 22:45:32 INFO - PROCESS | 1644 | RSS: Main: 207224832 22:45:32 INFO - PROCESS | 1644 | 22:48:35 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 22:48:36 INFO - PROCESS | 1644 | RSS: Main: 208437248 22:48:36 INFO - PROCESS | 1644 | 22:51:38 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 22:51:39 INFO - PROCESS | 1644 | RSS: Main: 209424384 22:51:39 INFO - PROCESS | 1644 | 22:51:39 INFO - PROCESS | 1644 | __start_tp_report 22:51:39 INFO - PROCESS | 1644 | _x_x_mozilla_page_load 22:51:39 INFO - PROCESS | 1644 | _x_x_mozilla_page_load_details 22:51:39 INFO - PROCESS | 1644 | |i|pagename|runs| 22:51:39 INFO - PROCESS | 1644 | |0;dojo.html;3559.822008899556;3595.44270638051;3595.999999999998;3600.1179710970814;3604.171040660648;6045.939540604598;6070.57505974582;6096.420840020191;6099.1766111574925;6126.7855625053135;6071.332153463127;6090.299615544213;6130.969345153279;6163.8150855474405;6172.413620706037;5408.891822163564;5483.533899618537;5489.368722596912;5500.477454641823;5527.474889885474;5510.448955104497;5538.224648549186;5547.362053363854;5554.2779438672915;5578.63738856975;4255.787100676308;4991.675541089836;5525.696086715229;5530.2810634617645;5545.722713864311;5494.532964698005;5503.669779813204;5505.311836020497;5548.140038294071;5556.222128901957;5323.680579165262;5429.239906413105;5454.1546060360315;5489.39616642169;5494.450554944514;5878.235829342201;6035.909461358083;6117.908231376533;6142.7850025249345;6165.445109940082;6136.447719705248;6251.187345645082;6302.369763023707;6322.652254126021;6331.081993111019;6458.870822583569;6488.610683359014;6488.675566221717;6503.512236582228;6535.8692826143215;6181.350958149419;6202.906956395657;6240.87518249637;6252.280987686383;6256.998880179195;5568.220449137124;6213.968930155411;6491.740330386826;6531.575447595891;6547.770828021092;6272.874542509125;6274.403931626488;6287.1512345832725;6298.527610429144;6307.990721484633;6449.903251451232;6457;6466.35336466359;6474.838129046717;6497.675116244169;6211.720472578745;6231.781887633911;6260.655663938436;6266.279377871603;6319.462845658079;5445.556927414238;6192.783252586228;6298.779542715983;6360.745570177233;6375.4262116409745;5149.6910185389;5192.1952097424955;5195.142801437796;5212.687238765686;5218.947810521846;4530.97734511332;4545;4615.284630882219;4638.420197475316;4675.345451636774;6126.846828829315;6139.3553676863285;6164.599301045418;6189.6286222826775;6214.161088253028;6495.415412612888;6505.381988711065;6533.411992920564;6537.967310163419;6546.116274303004;6098.908516372258;6131.193168498116;6156.938430615636;6164.845878853064;6189.907151392733;5596.692181929951;5695.145728140812;5721.941440833376;5737.311522617312;5759.942400576025;6625.238097618835;6644.435223006099;6657.40083392497;6699.196096468455;6710.530262881552;6245.219347581552;6273.62358258506;6395.136656551492;6413.877571424889;6431.324710905475;6662.700178491883;6673.699683514156;6676.9332306676315;6687.431568316553;6696.430803381572;6390.91354469732;6400.9039864403;6415.133956915756;6419.165508483868;6439.3238709934785;6296.118543403835;6306.842328941813;6313.116163737172;6433.388828061326;6487.54587178893;6020.458158765732;6239.875202496016;6255.499560035278;6269.467095296951;6305;6247.5939063960695;6250.593711408744;6273.466755325849;6282.591631543935;6296.685165741603;6171.444569988723;6179.980303250093;6199.907001395073;6265.216847894013;6305.022721478178;6208.751649933952;6210.596311239755;6211.472024877936;6247.219097612799;6272.278687950761;6257.655828929453;6266.248050234001;6285.96857015712;6286.74853005893;6287.905681414691 22:51:39 INFO - PROCESS | 1644 | |1;ext.html;437.7657952995147;447.97088189267697;505.29763628556316;534.1187041381718;547.6303495140779;30813.537796933066;41728.58271417285;41737.58262417379;41770.373444398356;41806.79096604516;41643.37534936978;42370;42907.14185716284;42956.926076848096;42971.78514107433;8510.319174466043;9684;9684;11361.807010263927;11729.648110556696;10745.19411044171;10841.67474975748;11614.535418583246;11756;11780.528778848835;11774.05807535395;11789.17475776696;11789.646310610653;11793.587224447147;11831;10559.947200263989;11444.828327575051;11447.02700270478;11712.941435292854;11715.238509496856;11407.771844563145;11542.249753766062;11573.189876708633;11605.361705106216;11660.76678466426;31394.21514462134;37963.62036379628;38011.61988380108;38035.23929521422;38198.61801381978;4059.5128584569748;4072.8778136655947;4087.509498860126;4157.97921010396;4166.33338665812;28106.718932810818;28459.715402845915;28650.283742906384;28653.570196447068;29998.400031999237;3269.2807582331848;3377.4596064629786;3471.1669199391963;3525.2596954639557;3529.8941031769086;469428;471409;471598.6420067912;472210;473430;57033;58288.12567811486;58789.82420351569;58850;59027;60710;61082.083768743505;61100.38899610947;61284;61359.77280454366;16308.510744677678;16919.661606767797;16970.06664633457;16996.49010529686;17058.658826823394;11956.222845515014;12258;13631.591052268448;13759.862401376058;13900.235487048309;21335.78664213369;22135.557288854132;22223.333300001024;22226.222082227043;22294.331170064925;7237.059182306267;7394;7418.962905185547;7437.442191835634;7488.513246638951;874.3835595904872;875.734563555656;879.612970293069;907.4011152639226;914.7118657622827;28491.85754071258;30877.845610772252;31031.224219394695;31124;31519.054428367184;929.3401684803865;1072.7311735678982;1081.4106312059946;1134.5291703942937;1230.686175025363;14139;14817.40730370773;15890.76163857543;15930;16574.6685066298;647516;657833;663889;664752;666003;54629.726851365485;54751;55024.449755502734;55106.72446637821;55166;1262.8421447319085;1410.3582869794218;1435.095889589552;1464.5606318104526;1465.3332733606396;28835.279118022216;29491.262718431353;29498.852505738192;29662.406751865274;30142;47491.762541187076;48096;48178.27732583944;48191.27713084236;48320;579517;582895;587038;587482;589354.0532297312;23576.528469430858;24137.275881723577;25673.10144144946;25898.70506474631;26269.73730262673;28831.855840720662;41240.793796030826;41289.380659289534;41839.58160418357;42267.73196804101;29455.558166627947;29969;30178.396432071673;30380.784768609006;30864.691353086182;29880.103596892128;29936.101916942527;30064.398712026075;30483;30613.857030008578;34638;35413.46879796857;35640.465393019644;35768.28463430769;36263.81868090643;29137.56293655639;29610.26357095807;30204.54693179648;31505.12676504371;31877.36245275128;24731.752682473663;24860.875695621406;24919.377015574755;25082.498350033264;25252.24243272705 22:51:39 INFO - PROCESS | 1644 | |2;jquery.html;335.3075898270071;396.6806720589925;407.6270212755329;411.4363322248518;436.6441350299506;18580.907095464536;21042.263520776774;21576.460588485297;22233.999470023846;22264.443388915286;17746.733798993024;21998;22070.88964555179;22262.55474890501;22797;5999.700014999255;6255.280642726077;6325.114483972247;6328.0507923811565;6337.429631333179;6150.507959363263;6228.346023667517;6275.090111933767;6278.215223097113;6304.779332723356;6171.7531298748;6248.937510624881;6254.6247225166635;6260.530460215479;6266.185395898527;5993.310769261547;6243.469305109071;6287.276963149251;6297.464715499188;6319.842003949892;5361.892762144775;5446.782128714846;5537.058700021006;5557.360903496109;5558;27147.457050858873;27217.727822721914;27380.45239095227;27449.215800972874;27548.724512754816;2537.8223524353302;2686.1404350607813;2720.5375086235385;2902.368088916836;2919.0221275872395;10421.791564168674;10594.9470252649;10741.140708743282;10833.729156771065;10999.395033273167;1929.1222493765304;1938.5735138269558;1946.883187008784;1949.8440124789984;1955.4720225539024;145171;148835;153269.23365383214;153568.23215883962;154977;10529.210309226839;11900.880991189977;12181.756364872654;12410.317432541116;12544;14220.288985550682;14911.552653420415;15318.693626127415;15988.840111598967;16090.839091609168;8648.221660050625;8892.822143557092;9018.819623607491;9028.909710902806;9187.770305742277;8838.55807209637;8860.778480538038;8876.733697989072;9034.593443295067;9041.502717350477;4009.438678584942;4059.2490389278078;4700.929486057712;4800.255960326155;4933.876653083702;2070.98521724357;2132.7227460430045;2138.7968143026387;2143.98928005359;2152.160657343637;1042.2756184451732;1046.55215334827;1054.7363159210197;1070.5236169904317;1089.2647462962468;22031.559368812534;22311.330660080224;22402.439939001655;22488.55022899533;22558.774412255996;1063.3194755356578;1068.2255364860414;1097.0948967102013;1097.1771171621283;1102.063246240705;11097.1677124216;11684.298942063333;11784.528618855322;11788.46951887167;11790.410479475991;627194;629594;735939;756229;762870;126390.36804815917;126794;150809.24595376954;155253;159073;1027.3579013116803;1039.7088815131776;1056.3239526702916;1057.296897563112;1061.6337363609543;17899.389054985113;18732;19815.20739170417;19842.305519306756;19988.50028749293;58117.41882581289;58453.707731462775;58928.82142357043;59531;60426.69786651039;72345.63827181041;82258.70965161326;87274;87383.56308218672;90377;7601.353884919844;8380;8447.45091569046;8567.100454452193;8743.781405464913;11335;11668.358240296866;12223.144379893321;12289.754204916031;13405.396757145756;11243.381614011309;11278.605248816251;11558.190926635054;11610.24533405326;11705.536807899012;9239.029901860207;10110.69667909964;10574.524146413276;10805.459727013775;11038.11695064409;11337.546498140311;11613.012893904113;11654.825177622173;11806.527738890349;11808.409579520818;7191.568505889663;8417.28453081495;8626.094260102598;8676.002259739958;8845.69040083594;9327.860082098638;10727.141828653845;10815.24293299493;11107.666769996913;11313.71715707081 22:51:39 INFO - PROCESS | 1644 | |3;mootools.html;1227.7667243223784;1297.2281492511954;1467.7377455388364;1468.4126349460207;1477.6675248069187;7754.689812407511;8011.998500187477;8275.213854683803;8673.739787806368;8685;8165.428420010601;8466.576671166447;8488;8558.657653693861;8572.885524881773;2368.4434157972864;2402.4714562796157;2405.987970060156;2414.167112346238;2416.3113512648897;2151.128792838903;2230.966535501969;2389.8446600970883;2433.245693834917;2433.5132973405302;2183.257692384589;2254.7068881045525;2256.6953461282765;2270.3642979965553;2271.6706077618737;2025.3316405586122;2377.500724847784;2381.4760752634393;2400.7479214682558;2415.40822498489;2373.655819906121;2398.664187013802;2414.480886609387;2421.370443684637;2442.1452491627965;8547;8566.785830354229;8581.914180858237;8639.784005399915;8655.17775811297;5229.450907654717;5351.892962140735;5355;5356.812511562077;5366.221897824833;3443.1564266754554;3611.620779818134;3661.41417373219;3682.042668906077;3728.422094575318;1241.503398640542;1294.385341609952;1300.8504022037491;1322.2793577500286;1335.3323338330836;11017.338959662366;11023.503942322615;11124.276922000125;11156.665300041012;11192.44037798107;4627.791749371287;4767.380240568704;4822.493638167942;4825.927611085836;4845.3216549683075;9700.95149524262;9795.363301385387;9801.901980980241;9809.21526277896;9814.75463113428;5320.946790532045;5391.919121213185;5396.055690254189;5398.838034858961;5419.918701219485;4193.287141186006;4740.810367585327;4895.510448955076;4899.1426500362295;4921.8523444296725;3948.9012774680864;3998.980005099956;4030.8790736277374;4049.190161967618;4085.6527195188146;1203.8435003449495;1232.7534493101236;1288.4846061575258;1298.2535042350687;1307.4181989014987;790.0953408347525;803.1807556292665;832.2842355574202;839.3704721458906;885.3669626217286;4965.677230980047;5685.374608793029;5742.081266997344;5820.447057529528;5897.2333596633025;980.8381617033252;1015.4618052432223;1020.4285600063988;1025.1132770153763;1062.2511129653576;3729.5524537055185;4002.5597184310286;4035.1324465239527;4061.1724723874377;4079.326911059701;9974.152197063186;9975.750606234902;10161.441120738431;10197.439140847177;10278.434686092336;10040.945700701468;10187.847182292271;10283.897161028444;10292.742681432874;10296.176305895511;7992.560409177435;8051.758447246592;8066;8101.853008952632;8176.877346839921;5602.663840169603;5711.8000869969355;5738.626989245686;5895.852603684943;5905.586608937333;9289.674861379819;9333.766655833659;9337.813243734952;9353.20497757671;9496.43021418717;9358.859617105612;9449.338546301968;9480.383775054599;9647.421154730739;9764.170045546209;6724.394804467622;6929.826754331182;7015.964920175366;7052.18899826506;7091.829848075216;8042.517448953082;8117.228863257981;8189.918100818915;8208.507489550646;8218.506889586644;6631.801045968628;6659.933400665931;7120.216776154515;7143.392811611071;7183.173934997542;6309.779157729457;6720.529562930548;7013.4038606719005;7015.824604384931;7068.540544864773;6504.349565043458;6526.477881769542;6594.901076483952;6617.106690596708;6662.866742665023;5115.130427827204;5117.923231151462;5138.152204886302;5191.065608190562;5192.610554208525;3980.5024371953505;5031.547160755549;5035.118854200427;5267.236250743544;5319.574434045345 22:51:39 INFO - PROCESS | 1644 | |4;prototype.html;314.2865694872639;376.54438129862865;377.1721072246419;379.81199306343365;385.76275590511824;4440.489343725472;5560.749766260518;5564.972175139119;5604.719764011798;5639.436056394358;4873.683210591318;5112.565431938289;5130.7424733117405;5405.810796622119;5467.699276539789;3574.749767516275;3918.8628398006076;4057.5942405759333;4129.545749967501;4133.111381053088;3720.627937206285;4081.7346872453345;4095.1809638072355;4126.401671757593;4145.316022856225;3386.847591858372;3886.475325831019;3900.3174444472247;4066.166435880652;4132.462779838616;3623.710103191738;4007.579204183577;4092.3656833190903;4114.19773144237;4129.194807012649;3066.693330666938;3542.8582856685957;3558.928821423583;3570.2505772171708;3579.6062433132333;4162.500499939996;5308.495692909168;5329.973350133225;5389.083855744494;5397.7571009304675;3587.551556055493;4585.219002578242;4766.761661916925;4784.162771515006;4817.686850354716;4707.493602047346;5576.1356989666665;5710.514606258473;5747.890258465176;5766.394528574523;3345.43127668297;3455;3933.2330195611867;3946.3291240489184;3961.900952476211;5328.600354973392;5698.976863213541;5949.88778983459;6033.396660333975;6103.816885493442;3418.700893660393;3580.7493475456467;3834.9424758628643;3954.7429417087797;4039.6162364575316;3129.85915633797;3868.0602741916346;3975.7216994810087;3994.8601798936897;4026;3607.4408466687705;3995.5815685431835;4231.597998190167;4444.910996805803;4685.648576356787;4403.974171880906;5238.7904483820985;5667.518260947865;5676.615821355041;5830.533557315404;1106.950187241576;1170.7190274334098;1288.3365066990507;1291.9806202906964;1294.5986744109168;719.6977269546803;824.7773101262626;892.3218354050967;893.4237416866085;895.6865097216053;5118.999999999925;5124.9500199919585;5125.384953805567;5127.000234954148;5187.896242075213;4618.08349534946;4879.609631229493;4907.061710624354;5609.326880774252;5641.765848737168;4695.600873925755;5093.745312734349;5155.355580552431;5191.714455704972;5241.42344342122;2324.9883750581475;2534.82256242065;2807.5367564352064;2920.32832448538;2958.8816447342297;4716.386869706985;5201.557867581298;5774.931637647049;5832.445917637818;5881.323822710507;5142.1488264224845;5533.6403133796175;5603.333628867804;5729.914051289234;5731.309263767199;5710.657360558297;5836.620619659709;5981.680167507076;6191.5665903388135;6267.373262673696;4870.563183860699;5110.1568241239775;5385.092050292798;5491.638595404014;6004.459598636144;4990.8752281193265;5620.522255608155;5823.301203855564;5829.883402332014;5974.790882319098;5932.025542886363;6009.128676341993;6061.787837425669;6082.661814400824;6127.763366371153;3561.893143205708;3968.940465892956;3970.980145099256;3984.06374501986;3986.242613903349;4720.811167553259;5495.477929596682;5679.444250087559;5923.963306421445;6083.087536869328;5010.824621138243;5241.266222728745;5698.458646428583;5742.224799651994;5791.507721843691;5583.6878333592385;5727.6849773262875;5752.712364381848;5762.394948530344;5822.7670893163795;4912.336834527293;5240.528352448146;5460.235567020541;5614;5681.0910254359105;5461.863453413537;5736.942630573641;5754.712264386848;5762.5389968801555;5766.538676905753;4758.881027974328;5383.569314454912;5641.717914104361;5643.209950606836;5651.378348381757 22:51:39 INFO - PROCESS | 1644 | |5;yui.html;733.537871141181;747.3423387419074;777.8172129549555;856.9014563325217;864.7232885476648;15423.768643470343;15792;16143.192840357964;16314.83685163148;16545.834541654578;14757.92621036896;14767.92616036921;15088.622784430394;15118.848811511882;15125.470608528703;776.7242628866755;815.946963447377;886.9733907982771;894.8702438146497;909.1090731083542;731.9853602927939;748.3788455581881;856.7001549457672;884.823035392924;907.2107266677999;928.8606708993638;937.873387092744;949.9620015199383;957.3968399908048;958.1137447860735;932.0120672087542;932.28680059754;946.4084946908182;949.6059135458777;952.314333679753;695.8712638161957;706.9575825450489;708.6952610377535;709.2800807180715;710.4210068793968;15796.60508487297;16322.755158672511;16459.094749788757;16681.91659041709;16952.83047169537;15151.772723409158;15288.541343759705;15422.922885385613;15520.301586428637;15554;10621.893781062168;11949.163558550905;12042.524046335722;12082.577109801203;12327.630171094881;3851.306764782338;3878.3019056569806;3880.8059597020037;3889.2250314926832;3906.726529142961;92766.14467710607;94246.58630120622;95020;119540.60918781663;122917;9757.292228952952;9955.203583713286;10078.596856125672;10084.546195421224;10100.444475553915;19239.51901202453;22863.31410057701;22930.65604015941;22944.770552294263;22962;8018.438709290293;8333;8368.497890126611;8424.283935865398;8603.182697643713;4595.839145629954;4616.413470944041;4636.466806317317;4698.154332220233;4796.592289655348;3583.8208089595414;3657.9451308230396;3666.3583872822683;3678.08047988003;3682.1531047858607;608.4311169056946;640.8718256348656;672.3679741043403;675.4798804920282;701.7789396340137;679.6669631880343;733.9743108991266;740.1155619035309;740.489062546841;766.1151370167465;9907.455089969977;10574.629887953884;10623.043926046694;10715.732106697238;10717.89282107169;676.3169199108935;684.2917580304461;714.3891972363658;738.3871386748986;740.3891789273871;5069.426054631476;5194.97402512985;5205.765740541685;5209.374875015023;5222.21666749983;59933;85746.57126714327;85788.57105714432;89207.55396223108;107428;83454;84374.5781271102;84715.57642211749;85145;87268.56365718131;76542;77551.61224193843;77620.44759104899;78420;79587;9919.20646348276;10960.397178155277;11128.011529964326;11401.258918170292;11433.885661143282;42736.78631606822;44763.77618111888;47323.290150648456;48897.75551122222;49689;52883.471165287854;68034.31965680279;70494;71011;79173;14601.561953141423;14843.480478183212;14849.257537123316;14949.402023919356;14965.027273227206;15477.5356739298;15652.92173539125;15671.981321214085;16177.191140443167;16490.257938393042;14090.506832260822;14559.563213103624;14574.854251457773;14577.344019518934;14604.196769177797;14098.647533811327;14444.711105777615;14630.341634626255;14637.121772693672;15079.396824126912;13762.931185344009;14129.364178611782;14431.42274309058;14435;14497.20265385414;8445;8892.86660700103;9776.064066439158;9921.590632588406;10524.421156836448;8856.778580535538;9659.903400966181;10501.842472363074;11786;12777.23336599807 22:51:39 INFO - PROCESS | 1644 | __end_tp_report 22:51:39 INFO - PROCESS | 1644 | __start_cc_report 22:51:39 INFO - PROCESS | 1644 | _x_x_mozilla_cycle_collect,370 22:51:39 INFO - PROCESS | 1644 | __end_cc_report 22:51:39 INFO - PROCESS | 1644 | __startTimestamp1491198699243__endTimestamp 22:51:39 INFO - PROCESS | 1644 | 22:51:39 INFO - PROCESS | 1644 | ------- Summary: start ------- 22:51:39 INFO - PROCESS | 1644 | Number of tests: 6 22:51:39 INFO - PROCESS | 1644 | 22:51:39 INFO - PROCESS | 1644 | [#0] dojo.html Cycles:165 Average:5984.12 Median:6211.72 stddev:643.85 (10.4%) stddev-sans-first:617.10 22:51:39 INFO - PROCESS | 1644 | Values: 3559.8 3595.4 3596.0 3600.1 3604.2 6045.9 6070.6 6096.4 6099.2 6126.8 6071.3 6090.3 6131.0 6163.8 6172.4 5408.9 5483.5 5489.4 5500.5 5527.5 5510.4 5538.2 5547.4 5554.3 5578.6 4255.8 4991.7 5525.7 5530.3 5545.7 5494.5 5503.7 5505.3 5548.1 5556.2 5323.7 5429.2 5454.2 5489.4 5494.5 5878.2 6035.9 6117.9 6142.8 6165.4 6136.4 6251.2 6302.4 6322.7 6331.1 6458.9 6488.6 6488.7 6503.5 6535.9 6181.4 6202.9 6240.9 6252.3 6257.0 5568.2 6214.0 6491.7 6531.6 6547.8 6272.9 6274.4 6287.2 6298.5 6308.0 6449.9 6457.0 6466.4 6474.8 6497.7 6211.7 6231.8 6260.7 6266.3 6319.5 5445.6 6192.8 6298.8 6360.7 6375.4 5149.7 5192.2 5195.1 5212.7 5218.9 4531.0 4545.0 4615.3 4638.4 4675.3 6126.8 6139.4 6164.6 6189.6 6214.2 6495.4 6505.4 6533.4 6538.0 6546.1 6098.9 6131.2 6156.9 6164.8 6189.9 5596.7 5695.1 5721.9 5737.3 5759.9 6625.2 6644.4 6657.4 6699.2 6710.5 6245.2 6273.6 6395.1 6413.9 6431.3 6662.7 6673.7 6676.9 6687.4 6696.4 6390.9 6400.9 6415.1 6419.2 6439.3 6296.1 6306.8 6313.1 6433.4 6487.5 6020.5 6239.9 6255.5 6269.5 6305.0 6247.6 6250.6 6273.5 6282.6 6296.7 6171.4 6180.0 6199.9 6265.2 6305.0 6208.8 6210.6 6211.5 6247.2 6272.3 6257.7 6266.2 6286.0 6286.7 6287.9 22:51:39 INFO - PROCESS | 1644 | 22:51:39 INFO - PROCESS | 1644 | [#1] ext.html Cycles:180 Average:69590.15 Median:31449.67 stddev:154527.71 (491.3%) stddev-sans-first:154873.98 22:51:39 INFO - PROCESS | 1644 | Values: 437.8 448.0 505.3 534.1 547.6 30813.5 41728.6 41737.6 41770.4 41806.8 41643.4 42370.0 42907.1 42956.9 42971.8 8510.3 9684.0 9684.0 11361.8 11729.6 10745.2 10841.7 11614.5 11756.0 11780.5 11774.1 11789.2 11789.6 11793.6 11831.0 10559.9 11444.8 11447.0 11712.9 11715.2 11407.8 11542.2 11573.2 11605.4 11660.8 31394.2 37963.6 38011.6 38035.2 38198.6 4059.5 4072.9 4087.5 4158.0 4166.3 28106.7 28459.7 28650.3 28653.6 29998.4 3269.3 3377.5 3471.2 3525.3 3529.9 469428.0 471409.0 471598.6 472210.0 473430.0 57033.0 58288.1 58789.8 58850.0 59027.0 60710.0 61082.1 61100.4 61284.0 61359.8 16308.5 16919.7 16970.1 16996.5 17058.7 11956.2 12258.0 13631.6 13759.9 13900.2 21335.8 22135.6 22223.3 22226.2 22294.3 7237.1 7394.0 7419.0 7437.4 7488.5 874.4 875.7 879.6 907.4 914.7 28491.9 30877.8 31031.2 31124.0 31519.1 929.3 1072.7 1081.4 1134.5 1230.7 14139.0 14817.4 15890.8 15930.0 16574.7 647516.0 657833.0 663889.0 664752.0 666003.0 54629.7 54751.0 55024.4 55106.7 55166.0 1262.8 1410.4 1435.1 1464.6 1465.3 28835.3 29491.3 29498.9 29662.4 30142.0 47491.8 48096.0 48178.3 48191.3 48320.0 579517.0 582895.0 587038.0 587482.0 589354.1 23576.5 24137.3 25673.1 25898.7 26269.7 28831.9 41240.8 41289.4 41839.6 42267.7 29455.6 29969.0 30178.4 30380.8 30864.7 29880.1 29936.1 30064.4 30483.0 30613.9 34638.0 35413.5 35640.5 35768.3 36263.8 29137.6 29610.3 30204.5 31505.1 31877.4 24731.8 24860.9 24919.4 25082.5 25252.2 22:51:39 INFO - PROCESS | 1644 | 22:51:39 INFO - PROCESS | 1644 | [#2] jquery.html Cycles:180 Average:39931.56 Median:22248.28 stddev:117971.87 (530.3%) stddev-sans-first:118265.35 22:51:39 INFO - PROCESS | 1644 | Values: 335.3 396.7 407.6 411.4 436.6 18580.9 21042.3 21576.5 22234.0 22264.4 17746.7 21998.0 22070.9 22262.6 22797.0 5999.7 6255.3 6325.1 6328.1 6337.4 6150.5 6228.3 6275.1 6278.2 6304.8 6171.8 6248.9 6254.6 6260.5 6266.2 5993.3 6243.5 6287.3 6297.5 6319.8 5361.9 5446.8 5537.1 5557.4 5558.0 27147.5 27217.7 27380.5 27449.2 27548.7 2537.8 2686.1 2720.5 2902.4 2919.0 10421.8 10594.9 10741.1 10833.7 10999.4 1929.1 1938.6 1946.9 1949.8 1955.5 145171.0 148835.0 153269.2 153568.2 154977.0 10529.2 11900.9 12181.8 12410.3 12544.0 14220.3 14911.6 15318.7 15988.8 16090.8 8648.2 8892.8 9018.8 9028.9 9187.8 8838.6 8860.8 8876.7 9034.6 9041.5 4009.4 4059.2 4700.9 4800.3 4933.9 2071.0 2132.7 2138.8 2144.0 2152.2 1042.3 1046.6 1054.7 1070.5 1089.3 22031.6 22311.3 22402.4 22488.6 22558.8 1063.3 1068.2 1097.1 1097.2 1102.1 11097.2 11684.3 11784.5 11788.5 11790.4 627194.0 629594.0 735939.0 756229.0 762870.0 126390.4 126794.0 150809.2 155253.0 159073.0 1027.4 1039.7 1056.3 1057.3 1061.6 17899.4 18732.0 19815.2 19842.3 19988.5 58117.4 58453.7 58928.8 59531.0 60426.7 72345.6 82258.7 87274.0 87383.6 90377.0 7601.4 8380.0 8447.5 8567.1 8743.8 11335.0 11668.4 12223.1 12289.8 13405.4 11243.4 11278.6 11558.2 11610.2 11705.5 9239.0 10110.7 10574.5 10805.5 11038.1 11337.5 11613.0 11654.8 11806.5 11808.4 7191.6 8417.3 8626.1 8676.0 8845.7 9327.9 10727.1 10815.2 11107.7 11313.7 22:51:39 INFO - PROCESS | 1644 | 22:51:39 INFO - PROCESS | 1644 | [#3] mootools.html Cycles:180 Average:5551.18 Median:5211.03 stddev:3018.84 (57.9%) stddev-sans-first:3009.82 22:51:39 INFO - PROCESS | 1644 | Values: 1227.8 1297.2 1467.7 1468.4 1477.7 7754.7 8012.0 8275.2 8673.7 8685.0 8165.4 8466.6 8488.0 8558.7 8572.9 2368.4 2402.5 2406.0 2414.2 2416.3 2151.1 2231.0 2389.8 2433.2 2433.5 2183.3 2254.7 2256.7 2270.4 2271.7 2025.3 2377.5 2381.5 2400.7 2415.4 2373.7 2398.7 2414.5 2421.4 2442.1 8547.0 8566.8 8581.9 8639.8 8655.2 5229.5 5351.9 5355.0 5356.8 5366.2 3443.2 3611.6 3661.4 3682.0 3728.4 1241.5 1294.4 1300.9 1322.3 1335.3 11017.3 11023.5 11124.3 11156.7 11192.4 4627.8 4767.4 4822.5 4825.9 4845.3 9701.0 9795.4 9801.9 9809.2 9814.8 5320.9 5391.9 5396.1 5398.8 5419.9 4193.3 4740.8 4895.5 4899.1 4921.9 3948.9 3999.0 4030.9 4049.2 4085.7 1203.8 1232.8 1288.5 1298.3 1307.4 790.1 803.2 832.3 839.4 885.4 4965.7 5685.4 5742.1 5820.4 5897.2 980.8 1015.5 1020.4 1025.1 1062.3 3729.6 4002.6 4035.1 4061.2 4079.3 9974.2 9975.8 10161.4 10197.4 10278.4 10040.9 10187.8 10283.9 10292.7 10296.2 7992.6 8051.8 8066.0 8101.9 8176.9 5602.7 5711.8 5738.6 5895.9 5905.6 9289.7 9333.8 9337.8 9353.2 9496.4 9358.9 9449.3 9480.4 9647.4 9764.2 6724.4 6929.8 7016.0 7052.2 7091.8 8042.5 8117.2 8189.9 8208.5 8218.5 6631.8 6659.9 7120.2 7143.4 7183.2 6309.8 6720.5 7013.4 7015.8 7068.5 6504.3 6526.5 6594.9 6617.1 6662.9 5115.1 5117.9 5138.2 5191.1 5192.6 3980.5 5031.5 5035.1 5267.2 5319.6 22:51:39 INFO - PROCESS | 1644 | 22:51:39 INFO - PROCESS | 1644 | [#4] prototype.html Cycles:180 Average:4540.81 Median:5125.17 stddev:1433.37 (28.0%) stddev-sans-first:1401.85 22:51:39 INFO - PROCESS | 1644 | Values: 314.3 376.5 377.2 379.8 385.8 4440.5 5560.7 5565.0 5604.7 5639.4 4873.7 5112.6 5130.7 5405.8 5467.7 3574.7 3918.9 4057.6 4129.5 4133.1 3720.6 4081.7 4095.2 4126.4 4145.3 3386.8 3886.5 3900.3 4066.2 4132.5 3623.7 4007.6 4092.4 4114.2 4129.2 3066.7 3542.9 3558.9 3570.3 3579.6 4162.5 5308.5 5330.0 5389.1 5397.8 3587.6 4585.2 4766.8 4784.2 4817.7 4707.5 5576.1 5710.5 5747.9 5766.4 3345.4 3455.0 3933.2 3946.3 3961.9 5328.6 5699.0 5949.9 6033.4 6103.8 3418.7 3580.7 3834.9 3954.7 4039.6 3129.9 3868.1 3975.7 3994.9 4026.0 3607.4 3995.6 4231.6 4444.9 4685.6 4404.0 5238.8 5667.5 5676.6 5830.5 1107.0 1170.7 1288.3 1292.0 1294.6 719.7 824.8 892.3 893.4 895.7 5119.0 5125.0 5125.4 5127.0 5187.9 4618.1 4879.6 4907.1 5609.3 5641.8 4695.6 5093.7 5155.4 5191.7 5241.4 2325.0 2534.8 2807.5 2920.3 2958.9 4716.4 5201.6 5774.9 5832.4 5881.3 5142.1 5533.6 5603.3 5729.9 5731.3 5710.7 5836.6 5981.7 6191.6 6267.4 4870.6 5110.2 5385.1 5491.6 6004.5 4990.9 5620.5 5823.3 5829.9 5974.8 5932.0 6009.1 6061.8 6082.7 6127.8 3561.9 3968.9 3971.0 3984.1 3986.2 4720.8 5495.5 5679.4 5924.0 6083.1 5010.8 5241.3 5698.5 5742.2 5791.5 5583.7 5727.7 5752.7 5762.4 5822.8 4912.3 5240.5 5460.2 5614.0 5681.1 5461.9 5736.9 5754.7 5762.5 5766.5 4758.9 5383.6 5641.7 5643.2 5651.4 22:51:39 INFO - PROCESS | 1644 | 22:51:39 INFO - PROCESS | 1644 | [#5] yui.html Cycles:180 Average:20109.74 Median:26847.17 stddev:27880.01 (103.8%) stddev-sans-first:27920.26 22:51:39 INFO - PROCESS | 1644 | Values: 733.5 747.3 777.8 856.9 864.7 15423.8 15792.0 16143.2 16314.8 16545.8 14757.9 14767.9 15088.6 15118.8 15125.5 776.7 815.9 887.0 894.9 909.1 732.0 748.4 856.7 884.8 907.2 928.9 937.9 950.0 957.4 958.1 932.0 932.3 946.4 949.6 952.3 695.9 707.0 708.7 709.3 710.4 15796.6 16322.8 16459.1 16681.9 16952.8 15151.8 15288.5 15422.9 15520.3 15554.0 10621.9 11949.2 12042.5 12082.6 12327.6 3851.3 3878.3 3880.8 3889.2 3906.7 92766.1 94246.6 95020.0 119540.6 122917.0 9757.3 9955.2 10078.6 10084.5 10100.4 19239.5 22863.3 22930.7 22944.8 22962.0 8018.4 8333.0 8368.5 8424.3 8603.2 4595.8 4616.4 4636.5 4698.2 4796.6 3583.8 3657.9 3666.4 3678.1 3682.2 608.4 640.9 672.4 675.5 701.8 679.7 734.0 740.1 740.5 766.1 9907.5 10574.6 10623.0 10715.7 10717.9 676.3 684.3 714.4 738.4 740.4 5069.4 5195.0 5205.8 5209.4 5222.2 59933.0 85746.6 85788.6 89207.6 107428.0 83454.0 84374.6 84715.6 85145.0 87268.6 76542.0 77551.6 77620.4 78420.0 79587.0 9919.2 10960.4 11128.0 11401.3 11433.9 42736.8 44763.8 47323.3 48897.8 49689.0 52883.5 68034.3 70494.0 71011.0 79173.0 14601.6 14843.5 14849.3 14949.4 14965.0 15477.5 15652.9 15672.0 16177.2 16490.3 14090.5 14559.6 14574.9 14577.3 14604.2 14098.6 14444.7 14630.3 14637.1 15079.4 13762.9 14129.4 14431.4 14435.0 14497.2 8445.0 8892.9 9776.1 9921.6 10524.4 8856.8 9659.9 10501.8 11786.0 12777.2 22:51:39 INFO - PROCESS | 1644 | -------- Summary: end -------- 22:51:39 INFO - PROCESS | 1644 | 22:51:39 INFO - TEST-INFO | 1644: exit 0 22:51:39 INFO - TEST-OK | dromaeo_css | took 1113976ms 22:51:39 INFO - TEST-START | kraken 22:51:39 INFO - operating with platform_type : mac_ 22:51:39 INFO - Initialising browser for kraken test... 22:51:39 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/tmpg3gT1c/profile http://localhost:49242/getInfo.html) 22:51:42 INFO - PROCESS | 1656 | 2017-04-02 22:51:42.421 plugin-container[1658:9922] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x853f, name = 'com.apple.tsm.portname' 22:51:42 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:51:42 INFO - PROCESS | 1656 | 2017-04-02 22:51:42.422 plugin-container[1658:9922] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x461b, name = 'com.apple.CFPasteboardClient' 22:51:42 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:51:42 INFO - PROCESS | 1656 | 2017-04-02 22:51:42.422 plugin-container[1658:9922] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:51:42 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 22:51:42 INFO - PROCESS | 1656 | colorDepth:24 22:51:42 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 22:51:42 INFO - PROCESS | 1656 | __metrics 22:51:42 ERROR - PROCESS | 1656 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 22:51:43 INFO - TEST-INFO | 1656: exit 0 22:51:43 INFO - Browser initialized. 22:51:43 INFO - Running cycle 1/1 for kraken test... 22:51:43 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/tmpg3gT1c/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:51:45 INFO - PROCESS | 1659 | 2017-04-02 22:51:45.374 plugin-container[1660:10047] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4443, name = 'com.apple.tsm.portname' 22:51:45 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:51:45 INFO - PROCESS | 1659 | 2017-04-02 22:51:45.375 plugin-container[1660:10047] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4617, name = 'com.apple.CFPasteboardClient' 22:51:45 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:51:45 INFO - PROCESS | 1659 | 2017-04-02 22:51:45.375 plugin-container[1660:10047] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:51:55 INFO - PROCESS | 1659 | RSS: Main: 173506560 22:51:55 INFO - PROCESS | 1659 | 22:51:58 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 22:51:59 INFO - PROCESS | 1659 | RSS: Main: 186474496 22:51:59 INFO - PROCESS | 1659 | 22:52:01 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 22:52:02 INFO - PROCESS | 1659 | RSS: Main: 194207744 22:52:02 INFO - PROCESS | 1659 | 22:52:05 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 22:52:06 INFO - PROCESS | 1659 | RSS: Main: 197984256 22:52:06 INFO - PROCESS | 1659 | 22:52:07 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 22:52:08 INFO - PROCESS | 1659 | RSS: Main: 198422528 22:52:08 INFO - PROCESS | 1659 | 22:52:10 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:52:11 INFO - PROCESS | 1659 | RSS: Main: 199593984 22:52:11 INFO - PROCESS | 1659 | 22:52:14 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 22:52:14 INFO - PROCESS | 1659 | RSS: Main: 204185600 22:52:14 INFO - PROCESS | 1659 | 22:52:17 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 22:52:18 INFO - PROCESS | 1659 | RSS: Main: 204312576 22:52:18 INFO - PROCESS | 1659 | 22:52:21 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 22:52:21 INFO - PROCESS | 1659 | RSS: Main: 203743232 22:52:21 INFO - PROCESS | 1659 | 22:52:23 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:52:24 INFO - PROCESS | 1659 | RSS: Main: 202575872 22:52:24 INFO - PROCESS | 1659 | 22:52:26 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:52:26 INFO - PROCESS | 1659 | RSS: Main: 202575872 22:52:26 INFO - PROCESS | 1659 | 22:52:28 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:52:29 INFO - PROCESS | 1659 | RSS: Main: 202473472 22:52:29 INFO - PROCESS | 1659 | 22:52:32 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:52:33 INFO - PROCESS | 1659 | RSS: Main: 202469376 22:52:33 INFO - PROCESS | 1659 | 22:52:35 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:52:36 INFO - PROCESS | 1659 | RSS: Main: 202964992 22:52:36 INFO - PROCESS | 1659 | 22:52:38 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 22:52:38 INFO - PROCESS | 1659 | RSS: Main: 204984320 22:52:38 INFO - PROCESS | 1659 | 22:52:38 INFO - PROCESS | 1659 | __start_tp_report 22:52:38 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 22:52:38 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 22:52:38 INFO - PROCESS | 1659 | |i|pagename|runs| 22:52:38 INFO - PROCESS | 1659 | |0;ai-astar;133;169;136;131;122;119;115;117;117;117 22:52:38 INFO - PROCESS | 1659 | |1;audio-beat-detection;140;129;109;145;136;111;110;110;130;106 22:52:38 INFO - PROCESS | 1659 | |2;audio-dft;200;195;190;199;199;198;195;193;196;196 22:52:38 INFO - PROCESS | 1659 | |3;audio-fft;84;60;57;86;60;58;59;59;71;60 22:52:38 INFO - PROCESS | 1659 | |4;audio-oscillator;85;71;83;73;70;58;82;80;101;109 22:52:38 INFO - PROCESS | 1659 | |5;imaging-gaussian-blur;89;89;90;88;92;85;79;133;111;87 22:52:38 INFO - PROCESS | 1659 | |6;imaging-darkroom;96;97;96;95;138;133;96;97;96;130 22:52:38 INFO - PROCESS | 1659 | |7;imaging-desaturate;92;96;93;112;124;92;90;92;91;92 22:52:38 INFO - PROCESS | 1659 | |8;json-parse-financial;60;59;57;60;59;61;61;61;62;61 22:52:38 INFO - PROCESS | 1659 | |9;json-stringify-tinderbox;75;93;98;83;75;77;90;102;99;100 22:52:38 INFO - PROCESS | 1659 | |10;stanford-crypto-aes;82;82;82;85;87;80;84;91;84;88 22:52:38 INFO - PROCESS | 1659 | |11;stanford-crypto-ccm;135;175;154;152;134;130;136;142;196;129 22:52:38 INFO - PROCESS | 1659 | |12;stanford-crypto-pbkdf2;167;166;165;165;165;167;163;165;165;165 22:52:38 INFO - PROCESS | 1659 | |13;stanford-crypto-sha256-iterative;65;64;67;65;66;65;63;64;64;65 22:52:38 INFO - PROCESS | 1659 | __end_tp_report 22:52:38 INFO - PROCESS | 1659 | __start_cc_report 22:52:38 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,940 22:52:38 INFO - PROCESS | 1659 | __end_cc_report 22:52:38 INFO - PROCESS | 1659 | __startTimestamp1491198758534__endTimestamp 22:52:38 INFO - PROCESS | 1659 | 22:52:38 INFO - PROCESS | 1659 | ------- Summary: start ------- 22:52:38 INFO - PROCESS | 1659 | Number of tests: 14 22:52:38 INFO - PROCESS | 1659 | 22:52:38 INFO - PROCESS | 1659 | [#0] ai-astar Cycles:10 Average:127.60 Median:126.50 stddev:16.42 (13.0%) stddev-sans-first:17.30 22:52:38 INFO - PROCESS | 1659 | Values: 133.0 169.0 136.0 131.0 122.0 119.0 115.0 117.0 117.0 117.0 22:52:38 INFO - PROCESS | 1659 | 22:52:38 INFO - PROCESS | 1659 | [#1] audio-beat-detection Cycles:10 Average:122.60 Median:129.50 stddev:14.88 (11.5%) stddev-sans-first:14.39 22:52:38 INFO - PROCESS | 1659 | Values: 140.0 129.0 109.0 145.0 136.0 111.0 110.0 110.0 130.0 106.0 22:52:38 INFO - PROCESS | 1659 | 22:52:38 INFO - PROCESS | 1659 | [#2] audio-dft Cycles:10 Average:196.10 Median:197.00 stddev:3.07 (1.6%) stddev-sans-first:2.92 22:52:38 INFO - PROCESS | 1659 | Values: 200.0 195.0 190.0 199.0 199.0 198.0 195.0 193.0 196.0 196.0 22:52:38 INFO - PROCESS | 1659 | 22:52:38 INFO - PROCESS | 1659 | [#3] audio-fft Cycles:10 Average:65.40 Median:60.00 stddev:11.04 (18.4%) stddev-sans-first:9.43 22:52:38 INFO - PROCESS | 1659 | Values: 84.0 60.0 57.0 86.0 60.0 58.0 59.0 59.0 71.0 60.0 22:52:38 INFO - PROCESS | 1659 | 22:52:38 INFO - PROCESS | 1659 | [#4] audio-oscillator Cycles:10 Average:81.20 Median:76.50 stddev:14.98 (19.6%) stddev-sans-first:15.83 22:52:38 INFO - PROCESS | 1659 | Values: 85.0 71.0 83.0 73.0 70.0 58.0 82.0 80.0 101.0 109.0 22:52:38 INFO - PROCESS | 1659 | 22:52:38 INFO - PROCESS | 1659 | [#5] imaging-gaussian-blur Cycles:10 Average:94.30 Median:88.50 stddev:15.88 (17.9%) stddev-sans-first:16.73 22:52:38 INFO - PROCESS | 1659 | Values: 89.0 89.0 90.0 88.0 92.0 85.0 79.0 133.0 111.0 87.0 22:52:38 INFO - PROCESS | 1659 | 22:52:38 INFO - PROCESS | 1659 | [#6] imaging-darkroom Cycles:10 Average:107.40 Median:96.00 stddev:18.23 (19.0%) stddev-sans-first:18.87 22:52:38 INFO - PROCESS | 1659 | Values: 96.0 97.0 96.0 95.0 138.0 133.0 96.0 97.0 96.0 130.0 22:52:38 INFO - PROCESS | 1659 | 22:52:38 INFO - PROCESS | 1659 | [#7] imaging-desaturate Cycles:10 Average:97.40 Median:92.00 stddev:11.33 (12.3%) stddev-sans-first:11.84 22:52:38 INFO - PROCESS | 1659 | Values: 92.0 96.0 93.0 112.0 124.0 92.0 90.0 92.0 91.0 92.0 22:52:38 INFO - PROCESS | 1659 | 22:52:38 INFO - PROCESS | 1659 | [#8] json-parse-financial Cycles:10 Average:60.10 Median:61.00 stddev:1.45 (2.4%) stddev-sans-first:1.54 22:52:38 INFO - PROCESS | 1659 | Values: 60.0 59.0 57.0 60.0 59.0 61.0 61.0 61.0 62.0 61.0 22:52:38 INFO - PROCESS | 1659 | 22:52:38 INFO - PROCESS | 1659 | [#9] json-stringify-tinderbox Cycles:10 Average:89.20 Median:86.50 stddev:10.85 (12.5%) stddev-sans-first:10.22 22:52:38 INFO - PROCESS | 1659 | Values: 75.0 93.0 98.0 83.0 75.0 77.0 90.0 102.0 99.0 100.0 22:52:38 INFO - PROCESS | 1659 | 22:52:38 INFO - PROCESS | 1659 | [#10] stanford-crypto-aes Cycles:10 Average:84.50 Median:84.50 stddev:3.34 (4.0%) stddev-sans-first:3.42 22:52:38 INFO - PROCESS | 1659 | Values: 82.0 82.0 82.0 85.0 87.0 80.0 84.0 91.0 84.0 88.0 22:52:38 INFO - PROCESS | 1659 | 22:52:38 INFO - PROCESS | 1659 | [#11] stanford-crypto-ccm Cycles:10 Average:148.30 Median:147.00 stddev:21.89 (14.9%) stddev-sans-first:22.69 22:52:38 INFO - PROCESS | 1659 | Values: 135.0 175.0 154.0 152.0 134.0 130.0 136.0 142.0 196.0 129.0 22:52:38 INFO - PROCESS | 1659 | 22:52:38 INFO - PROCESS | 1659 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.30 Median:165.00 stddev:1.16 (0.7%) stddev-sans-first:1.05 22:52:38 INFO - PROCESS | 1659 | Values: 167.0 166.0 165.0 165.0 165.0 167.0 163.0 165.0 165.0 165.0 22:52:38 INFO - PROCESS | 1659 | 22:52:38 INFO - PROCESS | 1659 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.80 Median:65.00 stddev:1.14 (1.7%) stddev-sans-first:1.20 22:52:38 INFO - PROCESS | 1659 | Values: 65.0 64.0 67.0 65.0 66.0 65.0 63.0 64.0 64.0 65.0 22:52:38 INFO - PROCESS | 1659 | -------- Summary: end -------- 22:52:38 INFO - PROCESS | 1659 | 22:52:39 INFO - TEST-INFO | 1659: exit 0 22:52:39 INFO - TEST-OK | kraken | took 59526ms 22:52:39 INFO - SUITE-END | took 1173s 22:52:39 INFO - Completed test suite (00:19:33) 22:52:39 INFO - javascript benchmark 22:52:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7459.838247611017, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3559.822008899556, 3595.44270638051, 3595.999999999998, 3600.1179710970814, 3604.171040660648, 6045.939540604598, 6070.57505974582, 6096.420840020191, 6099.1766111574925, 6126.7855625053135, 6071.332153463127, 6090.299615544213, 6130.969345153279, 6163.8150855474405, 6172.413620706037, 5408.891822163564, 5483.533899618537, 5489.368722596912, 5500.477454641823, 5527.474889885474, 5510.448955104497, 5538.224648549186, 5547.362053363854, 5554.2779438672915, 5578.63738856975, 4255.787100676308, 4991.675541089836, 5525.696086715229, 5530.2810634617645, 5545.722713864311, 5494.532964698005, 5503.669779813204, 5505.311836020497, 5548.140038294071, 5556.222128901957, 5323.680579165262, 5429.239906413105, 5454.1546060360315, 5489.39616642169, 5494.450554944514, 5878.235829342201, 6035.909461358083, 6117.908231376533, 6142.7850025249345, 6165.445109940082, 6136.447719705248, 6251.187345645082, 6302.369763023707, 6322.652254126021, 6331.081993111019, 6458.870822583569, 6488.610683359014, 6488.675566221717, 6503.512236582228, 6535.8692826143215, 6181.350958149419, 6202.906956395657, 6240.87518249637, 6252.280987686383, 6256.998880179195, 5568.220449137124, 6213.968930155411, 6491.740330386826, 6531.575447595891, 6547.770828021092, 6272.874542509125, 6274.403931626488, 6287.1512345832725, 6298.527610429144, 6307.990721484633, 6449.903251451232, 6457.0, 6466.35336466359, 6474.838129046717, 6497.675116244169, 6211.720472578745, 6231.781887633911, 6260.655663938436, 6266.279377871603, 6319.462845658079, 5445.556927414238, 6192.783252586228, 6298.779542715983, 6360.745570177233, 6375.4262116409745, 5149.6910185389, 5192.1952097424955, 5195.142801437796, 5212.687238765686, 5218.947810521846, 4530.97734511332, 4545.0, 4615.284630882219, 4638.420197475316, 4675.345451636774, 6126.846828829315, 6139.3553676863285, 6164.599301045418, 6189.6286222826775, 6214.161088253028, 6495.415412612888, 6505.381988711065, 6533.411992920564, 6537.967310163419, 6546.116274303004, 6098.908516372258, 6131.193168498116, 6156.938430615636, 6164.845878853064, 6189.907151392733, 5596.692181929951, 5695.145728140812, 5721.941440833376, 5737.311522617312, 5759.942400576025, 6625.238097618835, 6644.435223006099, 6657.40083392497, 6699.196096468455, 6710.530262881552, 6245.219347581552, 6273.62358258506, 6395.136656551492, 6413.877571424889, 6431.324710905475, 6662.700178491883, 6673.699683514156, 6676.9332306676315, 6687.431568316553, 6696.430803381572, 6390.91354469732, 6400.9039864403, 6415.133956915756, 6419.165508483868, 6439.3238709934785, 6296.118543403835, 6306.842328941813, 6313.116163737172, 6433.388828061326, 6487.54587178893, 6020.458158765732, 6239.875202496016, 6255.499560035278, 6269.467095296951, 6305.0, 6247.5939063960695, 6250.593711408744, 6273.466755325849, 6282.591631543935, 6296.685165741603, 6171.444569988723, 6179.980303250093, 6199.907001395073, 6265.216847894013, 6305.022721478178, 6208.751649933952, 6210.596311239755, 6211.472024877936, 6247.219097612799, 6272.278687950761, 6257.655828929453, 6266.248050234001, 6285.96857015712, 6286.74853005893, 6287.905681414691], "value": 5944.721154149377, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [437.7657952995147, 447.97088189267697, 505.29763628556316, 534.1187041381718, 547.6303495140779, 30813.537796933066, 41728.58271417285, 41737.58262417379, 41770.373444398356, 41806.79096604516, 41643.37534936978, 42370.0, 42907.14185716284, 42956.926076848096, 42971.78514107433, 8510.319174466043, 9684.0, 9684.0, 11361.807010263927, 11729.648110556696, 10745.19411044171, 10841.67474975748, 11614.535418583246, 11756.0, 11780.528778848835, 11774.05807535395, 11789.17475776696, 11789.646310610653, 11793.587224447147, 11831.0, 10559.947200263989, 11444.828327575051, 11447.02700270478, 11712.941435292854, 11715.238509496856, 11407.771844563145, 11542.249753766062, 11573.189876708633, 11605.361705106216, 11660.76678466426, 31394.21514462134, 37963.62036379628, 38011.61988380108, 38035.23929521422, 38198.61801381978, 4059.5128584569748, 4072.8778136655947, 4087.509498860126, 4157.97921010396, 4166.33338665812, 28106.718932810818, 28459.715402845915, 28650.283742906384, 28653.570196447068, 29998.400031999237, 3269.2807582331848, 3377.4596064629786, 3471.1669199391963, 3525.2596954639557, 3529.8941031769086, 469428.0, 471409.0, 471598.6420067912, 472210.0, 473430.0, 57033.0, 58288.12567811486, 58789.82420351569, 58850.0, 59027.0, 60710.0, 61082.083768743505, 61100.38899610947, 61284.0, 61359.77280454366, 16308.510744677678, 16919.661606767797, 16970.06664633457, 16996.49010529686, 17058.658826823394, 11956.222845515014, 12258.0, 13631.591052268448, 13759.862401376058, 13900.235487048309, 21335.78664213369, 22135.557288854132, 22223.333300001024, 22226.222082227043, 22294.331170064925, 7237.059182306267, 7394.0, 7418.962905185547, 7437.442191835634, 7488.513246638951, 874.3835595904872, 875.734563555656, 879.612970293069, 907.4011152639226, 914.7118657622827, 28491.85754071258, 30877.845610772252, 31031.224219394695, 31124.0, 31519.054428367184, 929.3401684803865, 1072.7311735678982, 1081.4106312059946, 1134.5291703942937, 1230.686175025363, 14139.0, 14817.40730370773, 15890.76163857543, 15930.0, 16574.6685066298, 647516.0, 657833.0, 663889.0, 664752.0, 666003.0, 54629.726851365485, 54751.0, 55024.449755502734, 55106.72446637821, 55166.0, 1262.8421447319085, 1410.3582869794218, 1435.095889589552, 1464.5606318104526, 1465.3332733606396, 28835.279118022216, 29491.262718431353, 29498.852505738192, 29662.406751865274, 30142.0, 47491.762541187076, 48096.0, 48178.27732583944, 48191.27713084236, 48320.0, 579517.0, 582895.0, 587038.0, 587482.0, 589354.0532297312, 23576.528469430858, 24137.275881723577, 25673.10144144946, 25898.70506474631, 26269.73730262673, 28831.855840720662, 41240.793796030826, 41289.380659289534, 41839.58160418357, 42267.73196804101, 29455.558166627947, 29969.0, 30178.396432071673, 30380.784768609006, 30864.691353086182, 29880.103596892128, 29936.101916942527, 30064.398712026075, 30483.0, 30613.857030008578, 34638.0, 35413.46879796857, 35640.465393019644, 35768.28463430769, 36263.81868090643, 29137.56293655639, 29610.26357095807, 30204.54693179648, 31505.12676504371, 31877.36245275128, 24731.752682473663, 24860.875695621406, 24919.377015574755, 25082.498350033264, 25252.24243272705], "value": 19959.347952283537, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [335.3075898270071, 396.6806720589925, 407.6270212755329, 411.4363322248518, 436.6441350299506, 18580.907095464536, 21042.263520776774, 21576.460588485297, 22233.999470023846, 22264.443388915286, 17746.733798993024, 21998.0, 22070.88964555179, 22262.55474890501, 22797.0, 5999.700014999255, 6255.280642726077, 6325.114483972247, 6328.0507923811565, 6337.429631333179, 6150.507959363263, 6228.346023667517, 6275.090111933767, 6278.215223097113, 6304.779332723356, 6171.7531298748, 6248.937510624881, 6254.6247225166635, 6260.530460215479, 6266.185395898527, 5993.310769261547, 6243.469305109071, 6287.276963149251, 6297.464715499188, 6319.842003949892, 5361.892762144775, 5446.782128714846, 5537.058700021006, 5557.360903496109, 5558.0, 27147.457050858873, 27217.727822721914, 27380.45239095227, 27449.215800972874, 27548.724512754816, 2537.8223524353302, 2686.1404350607813, 2720.5375086235385, 2902.368088916836, 2919.0221275872395, 10421.791564168674, 10594.9470252649, 10741.140708743282, 10833.729156771065, 10999.395033273167, 1929.1222493765304, 1938.5735138269558, 1946.883187008784, 1949.8440124789984, 1955.4720225539024, 145171.0, 148835.0, 153269.23365383214, 153568.23215883962, 154977.0, 10529.210309226839, 11900.880991189977, 12181.756364872654, 12410.317432541116, 12544.0, 14220.288985550682, 14911.552653420415, 15318.693626127415, 15988.840111598967, 16090.839091609168, 8648.221660050625, 8892.822143557092, 9018.819623607491, 9028.909710902806, 9187.770305742277, 8838.55807209637, 8860.778480538038, 8876.733697989072, 9034.593443295067, 9041.502717350477, 4009.438678584942, 4059.2490389278078, 4700.929486057712, 4800.255960326155, 4933.876653083702, 2070.98521724357, 2132.7227460430045, 2138.7968143026387, 2143.98928005359, 2152.160657343637, 1042.2756184451732, 1046.55215334827, 1054.7363159210197, 1070.5236169904317, 1089.2647462962468, 22031.559368812534, 22311.330660080224, 22402.439939001655, 22488.55022899533, 22558.774412255996, 1063.3194755356578, 1068.2255364860414, 1097.0948967102013, 1097.1771171621283, 1102.063246240705, 11097.1677124216, 11684.298942063333, 11784.528618855322, 11788.46951887167, 11790.410479475991, 627194.0, 629594.0, 735939.0, 756229.0, 762870.0, 126390.36804815917, 126794.0, 150809.24595376954, 155253.0, 159073.0, 1027.3579013116803, 1039.7088815131776, 1056.3239526702916, 1057.296897563112, 1061.6337363609543, 17899.389054985113, 18732.0, 19815.20739170417, 19842.305519306756, 19988.50028749293, 58117.41882581289, 58453.707731462775, 58928.82142357043, 59531.0, 60426.69786651039, 72345.63827181041, 82258.70965161326, 87274.0, 87383.56308218672, 90377.0, 7601.353884919844, 8380.0, 8447.45091569046, 8567.100454452193, 8743.781405464913, 11335.0, 11668.358240296866, 12223.144379893321, 12289.754204916031, 13405.396757145756, 11243.381614011309, 11278.605248816251, 11558.190926635054, 11610.24533405326, 11705.536807899012, 9239.029901860207, 10110.69667909964, 10574.524146413276, 10805.459727013775, 11038.11695064409, 11337.546498140311, 11613.012893904113, 11654.825177622173, 11806.527738890349, 11808.409579520818, 7191.568505889663, 8417.28453081495, 8626.094260102598, 8676.002259739958, 8845.69040083594, 9327.860082098638, 10727.141828653845, 10815.24293299493, 11107.666769996913, 11313.71715707081], "value": 10178.351733900497, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1227.7667243223784, 1297.2281492511954, 1467.7377455388364, 1468.4126349460207, 1477.6675248069187, 7754.689812407511, 8011.998500187477, 8275.213854683803, 8673.739787806368, 8685.0, 8165.428420010601, 8466.576671166447, 8488.0, 8558.657653693861, 8572.885524881773, 2368.4434157972864, 2402.4714562796157, 2405.987970060156, 2414.167112346238, 2416.3113512648897, 2151.128792838903, 2230.966535501969, 2389.8446600970883, 2433.245693834917, 2433.5132973405302, 2183.257692384589, 2254.7068881045525, 2256.6953461282765, 2270.3642979965553, 2271.6706077618737, 2025.3316405586122, 2377.500724847784, 2381.4760752634393, 2400.7479214682558, 2415.40822498489, 2373.655819906121, 2398.664187013802, 2414.480886609387, 2421.370443684637, 2442.1452491627965, 8547.0, 8566.785830354229, 8581.914180858237, 8639.784005399915, 8655.17775811297, 5229.450907654717, 5351.892962140735, 5355.0, 5356.812511562077, 5366.221897824833, 3443.1564266754554, 3611.620779818134, 3661.41417373219, 3682.042668906077, 3728.422094575318, 1241.503398640542, 1294.385341609952, 1300.8504022037491, 1322.2793577500286, 1335.3323338330836, 11017.338959662366, 11023.503942322615, 11124.276922000125, 11156.665300041012, 11192.44037798107, 4627.791749371287, 4767.380240568704, 4822.493638167942, 4825.927611085836, 4845.3216549683075, 9700.95149524262, 9795.363301385387, 9801.901980980241, 9809.21526277896, 9814.75463113428, 5320.946790532045, 5391.919121213185, 5396.055690254189, 5398.838034858961, 5419.918701219485, 4193.287141186006, 4740.810367585327, 4895.510448955076, 4899.1426500362295, 4921.8523444296725, 3948.9012774680864, 3998.980005099956, 4030.8790736277374, 4049.190161967618, 4085.6527195188146, 1203.8435003449495, 1232.7534493101236, 1288.4846061575258, 1298.2535042350687, 1307.4181989014987, 790.0953408347525, 803.1807556292665, 832.2842355574202, 839.3704721458906, 885.3669626217286, 4965.677230980047, 5685.374608793029, 5742.081266997344, 5820.447057529528, 5897.2333596633025, 980.8381617033252, 1015.4618052432223, 1020.4285600063988, 1025.1132770153763, 1062.2511129653576, 3729.5524537055185, 4002.5597184310286, 4035.1324465239527, 4061.1724723874377, 4079.326911059701, 9974.152197063186, 9975.750606234902, 10161.441120738431, 10197.439140847177, 10278.434686092336, 10040.945700701468, 10187.847182292271, 10283.897161028444, 10292.742681432874, 10296.176305895511, 7992.560409177435, 8051.758447246592, 8066.0, 8101.853008952632, 8176.877346839921, 5602.663840169603, 5711.8000869969355, 5738.626989245686, 5895.852603684943, 5905.586608937333, 9289.674861379819, 9333.766655833659, 9337.813243734952, 9353.20497757671, 9496.43021418717, 9358.859617105612, 9449.338546301968, 9480.383775054599, 9647.421154730739, 9764.170045546209, 6724.394804467622, 6929.826754331182, 7015.964920175366, 7052.18899826506, 7091.829848075216, 8042.517448953082, 8117.228863257981, 8189.918100818915, 8208.507489550646, 8218.506889586644, 6631.801045968628, 6659.933400665931, 7120.216776154515, 7143.392811611071, 7183.173934997542, 6309.779157729457, 6720.529562930548, 7013.4038606719005, 7015.824604384931, 7068.540544864773, 6504.349565043458, 6526.477881769542, 6594.901076483952, 6617.106690596708, 6662.866742665023, 5115.130427827204, 5117.923231151462, 5138.152204886302, 5191.065608190562, 5192.610554208525, 3980.5024371953505, 5031.547160755549, 5035.118854200427, 5267.236250743544, 5319.574434045345], "value": 4513.506274360839, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [314.2865694872639, 376.54438129862865, 377.1721072246419, 379.81199306343365, 385.76275590511824, 4440.489343725472, 5560.749766260518, 5564.972175139119, 5604.719764011798, 5639.436056394358, 4873.683210591318, 5112.565431938289, 5130.7424733117405, 5405.810796622119, 5467.699276539789, 3574.749767516275, 3918.8628398006076, 4057.5942405759333, 4129.545749967501, 4133.111381053088, 3720.627937206285, 4081.7346872453345, 4095.1809638072355, 4126.401671757593, 4145.316022856225, 3386.847591858372, 3886.475325831019, 3900.3174444472247, 4066.166435880652, 4132.462779838616, 3623.710103191738, 4007.579204183577, 4092.3656833190903, 4114.19773144237, 4129.194807012649, 3066.693330666938, 3542.8582856685957, 3558.928821423583, 3570.2505772171708, 3579.6062433132333, 4162.500499939996, 5308.495692909168, 5329.973350133225, 5389.083855744494, 5397.7571009304675, 3587.551556055493, 4585.219002578242, 4766.761661916925, 4784.162771515006, 4817.686850354716, 4707.493602047346, 5576.1356989666665, 5710.514606258473, 5747.890258465176, 5766.394528574523, 3345.43127668297, 3455.0, 3933.2330195611867, 3946.3291240489184, 3961.900952476211, 5328.600354973392, 5698.976863213541, 5949.88778983459, 6033.396660333975, 6103.816885493442, 3418.700893660393, 3580.7493475456467, 3834.9424758628643, 3954.7429417087797, 4039.6162364575316, 3129.85915633797, 3868.0602741916346, 3975.7216994810087, 3994.8601798936897, 4026.0, 3607.4408466687705, 3995.5815685431835, 4231.597998190167, 4444.910996805803, 4685.648576356787, 4403.974171880906, 5238.7904483820985, 5667.518260947865, 5676.615821355041, 5830.533557315404, 1106.950187241576, 1170.7190274334098, 1288.3365066990507, 1291.9806202906964, 1294.5986744109168, 719.6977269546803, 824.7773101262626, 892.3218354050967, 893.4237416866085, 895.6865097216053, 5118.999999999925, 5124.9500199919585, 5125.384953805567, 5127.000234954148, 5187.896242075213, 4618.08349534946, 4879.609631229493, 4907.061710624354, 5609.326880774252, 5641.765848737168, 4695.600873925755, 5093.745312734349, 5155.355580552431, 5191.714455704972, 5241.42344342122, 2324.9883750581475, 2534.82256242065, 2807.5367564352064, 2920.32832448538, 2958.8816447342297, 4716.386869706985, 5201.557867581298, 5774.931637647049, 5832.445917637818, 5881.323822710507, 5142.1488264224845, 5533.6403133796175, 5603.333628867804, 5729.914051289234, 5731.309263767199, 5710.657360558297, 5836.620619659709, 5981.680167507076, 6191.5665903388135, 6267.373262673696, 4870.563183860699, 5110.1568241239775, 5385.092050292798, 5491.638595404014, 6004.459598636144, 4990.8752281193265, 5620.522255608155, 5823.301203855564, 5829.883402332014, 5974.790882319098, 5932.025542886363, 6009.128676341993, 6061.787837425669, 6082.661814400824, 6127.763366371153, 3561.893143205708, 3968.940465892956, 3970.980145099256, 3984.06374501986, 3986.242613903349, 4720.811167553259, 5495.477929596682, 5679.444250087559, 5923.963306421445, 6083.087536869328, 5010.824621138243, 5241.266222728745, 5698.458646428583, 5742.224799651994, 5791.507721843691, 5583.6878333592385, 5727.6849773262875, 5752.712364381848, 5762.394948530344, 5822.7670893163795, 4912.336834527293, 5240.528352448146, 5460.235567020541, 5614.0, 5681.0910254359105, 5461.863453413537, 5736.942630573641, 5754.712264386848, 5762.5389968801555, 5766.538676905753, 4758.881027974328, 5383.569314454912, 5641.717914104361, 5643.209950606836, 5651.378348381757], "value": 4096.654234416443, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [733.537871141181, 747.3423387419074, 777.8172129549555, 856.9014563325217, 864.7232885476648, 15423.768643470343, 15792.0, 16143.192840357964, 16314.83685163148, 16545.834541654578, 14757.92621036896, 14767.92616036921, 15088.622784430394, 15118.848811511882, 15125.470608528703, 776.7242628866755, 815.946963447377, 886.9733907982771, 894.8702438146497, 909.1090731083542, 731.9853602927939, 748.3788455581881, 856.7001549457672, 884.823035392924, 907.2107266677999, 928.8606708993638, 937.873387092744, 949.9620015199383, 957.3968399908048, 958.1137447860735, 932.0120672087542, 932.28680059754, 946.4084946908182, 949.6059135458777, 952.314333679753, 695.8712638161957, 706.9575825450489, 708.6952610377535, 709.2800807180715, 710.4210068793968, 15796.60508487297, 16322.755158672511, 16459.094749788757, 16681.91659041709, 16952.83047169537, 15151.772723409158, 15288.541343759705, 15422.922885385613, 15520.301586428637, 15554.0, 10621.893781062168, 11949.163558550905, 12042.524046335722, 12082.577109801203, 12327.630171094881, 3851.306764782338, 3878.3019056569806, 3880.8059597020037, 3889.2250314926832, 3906.726529142961, 92766.14467710607, 94246.58630120622, 95020.0, 119540.60918781663, 122917.0, 9757.292228952952, 9955.203583713286, 10078.596856125672, 10084.546195421224, 10100.444475553915, 19239.51901202453, 22863.31410057701, 22930.65604015941, 22944.770552294263, 22962.0, 8018.438709290293, 8333.0, 8368.497890126611, 8424.283935865398, 8603.182697643713, 4595.839145629954, 4616.413470944041, 4636.466806317317, 4698.154332220233, 4796.592289655348, 3583.8208089595414, 3657.9451308230396, 3666.3583872822683, 3678.08047988003, 3682.1531047858607, 608.4311169056946, 640.8718256348656, 672.3679741043403, 675.4798804920282, 701.7789396340137, 679.6669631880343, 733.9743108991266, 740.1155619035309, 740.489062546841, 766.1151370167465, 9907.455089969977, 10574.629887953884, 10623.043926046694, 10715.732106697238, 10717.89282107169, 676.3169199108935, 684.2917580304461, 714.3891972363658, 738.3871386748986, 740.3891789273871, 5069.426054631476, 5194.97402512985, 5205.765740541685, 5209.374875015023, 5222.21666749983, 59933.0, 85746.57126714327, 85788.57105714432, 89207.55396223108, 107428.0, 83454.0, 84374.5781271102, 84715.57642211749, 85145.0, 87268.56365718131, 76542.0, 77551.61224193843, 77620.44759104899, 78420.0, 79587.0, 9919.20646348276, 10960.397178155277, 11128.011529964326, 11401.258918170292, 11433.885661143282, 42736.78631606822, 44763.77618111888, 47323.290150648456, 48897.75551122222, 49689.0, 52883.471165287854, 68034.31965680279, 70494.0, 71011.0, 79173.0, 14601.561953141423, 14843.480478183212, 14849.257537123316, 14949.402023919356, 14965.027273227206, 15477.5356739298, 15652.92173539125, 15671.981321214085, 16177.191140443167, 16490.257938393042, 14090.506832260822, 14559.563213103624, 14574.854251457773, 14577.344019518934, 14604.196769177797, 14098.647533811327, 14444.711105777615, 14630.341634626255, 14637.121772693672, 15079.396824126912, 13762.931185344009, 14129.364178611782, 14431.42274309058, 14435.0, 14497.20265385414, 8445.0, 8892.86660700103, 9776.064066439158, 9921.590632588406, 10524.421156836448, 8856.778580535538, 9659.903400966181, 10501.842472363074, 11786.0, 12777.23336599807], "value": 7716.691428426305, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1504.1999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 169.0, 136.0, 131.0, 122.0, 119.0, 115.0, 117.0, 117.0, 117.0], "value": 127.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 129.0, 109.0, 145.0, 136.0, 111.0, 110.0, 110.0, 130.0, 106.0], "value": 122.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 195.0, 190.0, 199.0, 199.0, 198.0, 195.0, 193.0, 196.0, 196.0], "value": 196.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 60.0, 57.0, 86.0, 60.0, 58.0, 59.0, 59.0, 71.0, 60.0], "value": 65.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 71.0, 83.0, 73.0, 70.0, 58.0, 82.0, 80.0, 101.0, 109.0], "value": 81.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 90.0, 88.0, 92.0, 85.0, 79.0, 133.0, 111.0, 87.0], "value": 94.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 96.0, 95.0, 138.0, 133.0, 96.0, 97.0, 96.0, 130.0], "value": 107.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 96.0, 93.0, 112.0, 124.0, 92.0, 90.0, 92.0, 91.0, 92.0], "value": 97.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 59.0, 57.0, 60.0, 59.0, 61.0, 61.0, 61.0, 62.0, 61.0], "value": 60.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 93.0, 98.0, 83.0, 75.0, 77.0, 90.0, 102.0, 99.0, 100.0], "value": 89.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 82.0, 85.0, 87.0, 80.0, 84.0, 91.0, 84.0, 88.0], "value": 84.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 175.0, 154.0, 152.0, 134.0, 130.0, 136.0, 142.0, 196.0, 129.0], "value": 148.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 166.0, 165.0, 165.0, 165.0, 167.0, 163.0, 165.0, 165.0, 165.0], "value": 165.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 67.0, 65.0, 66.0, 65.0, 63.0, 64.0, 64.0, 65.0], "value": 64.8, "unit": "score"}]}]} 22:52:39 INFO - Return code: 0 22:52:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:52:39 ERROR - # TBPL SUCCESS # 22:52:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:52:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:52:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:52:39 INFO - 2017-04-02 22:52:39.869 screenresolution[1664:10428] starting screenresolution argv=screenresolution get 22:52:39 INFO - 2017-04-02 22:52:39.872 screenresolution[1664:10428] Display 0: 1600x1200x32@60 22:52:39 INFO - 2017-04-02 22:52:39.878 screenresolution[1665:10431] starting screenresolution argv=screenresolution list 22:52:39 INFO - Available Modes on Display 0 22:52:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:52:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:52:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:52:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:52:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:52:40 INFO - Intel Iris: 22:52:40 INFO - Chipset Model: Intel Iris 22:52:40 INFO - Type: GPU 22:52:40 INFO - Bus: Built-In 22:52:40 INFO - VRAM (Dynamic, Max): 1536 MB 22:52:40 INFO - Vendor: Intel (0x8086) 22:52:40 INFO - Device ID: 0x0a2e 22:52:40 INFO - Revision ID: 0x0009 22:52:40 INFO - Displays: 22:52:40 INFO - Display: 22:52:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:52:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:52:40 INFO - Display Serial Number: 1600x1200 60 22:52:40 INFO - Main Display: Yes 22:52:40 INFO - Mirror: Off 22:52:40 INFO - Online: Yes 22:52:40 INFO - Rotation: Supported 22:52:40 INFO - Return code: 0 22:52:40 INFO - Running post-action listener: _resource_record_post_action 22:52:40 INFO - [mozharness: 2017-04-03 05:52:40.111236Z] Finished run-tests step (success) 22:52:40 INFO - Running post-run listener: _resource_record_post_run 22:52:40 INFO - Total resource usage - Wall time: 1189s; CPU: 54.0%; Read bytes: 40556544; Write bytes: 426037760; Read time: 416; Write time: 1430 22:52:40 INFO - TinderboxPrint: CPU usage
54.2% 22:52:40 INFO - TinderboxPrint: I/O read bytes / time
40,556,544 / 416 22:52:40 INFO - TinderboxPrint: I/O write bytes / time
426,037,760 / 1,430 22:52:40 INFO - TinderboxPrint: CPU idle
2,181.1 (45.8%) 22:52:40 INFO - TinderboxPrint: CPU system
956.0 (20.1%) 22:52:40 INFO - TinderboxPrint: CPU user
1,620.6 (34.1%) 22:52:40 INFO - TinderboxPrint: Swap in / out
162,799,616 / 0 22:52:40 INFO - install - Wall time: 15s; CPU: 53.0%; Read bytes: 113353216; Write bytes: 114398720; Read time: 10063; Write time: 265 22:52:40 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 39630848; Write bytes: 301755392; Read time: 402; Write time: 1133 22:52:40 INFO - Running post-run listener: _upload_blobber_files 22:52:40 INFO - Blob upload gear active. 22:52:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:52:40 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 22:52:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:52:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:52:40 INFO - (blobuploader) - INFO - Open directory for files ... 22:52:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:52:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:52:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:52:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:52:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:52:41 INFO - (blobuploader) - INFO - Done attempting. 22:52:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:52:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:52:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:52:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:52:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:52:42 INFO - (blobuploader) - INFO - Done attempting. 22:52:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 22:52:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:52:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:52:43 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 22:52:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:52:43 INFO - (blobuploader) - INFO - Done attempting. 22:52:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:52:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:52:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:52:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:52:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:52:45 INFO - (blobuploader) - INFO - Done attempting. 22:52:45 INFO - (blobuploader) - INFO - Iteration through files over. 22:52:45 INFO - Return code: 0 22:52:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:52:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:52:45 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/370f1b44c507bed09644536520a1b2ae033b4f43b6fc14ad4e5fa236baeb83a26bebf1f7bc415d5cb67a3bc0b2bed13d875600a08c07289ad868dba436dcffee", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2e29d3dce5018e5525ce67b804e1f0802ab72ac6ae8b597f0f124071f631f70d019ba336fa96ebf22ba0438e949bb0287bf2ce1fcc4d7fca49594dbdaacb8806", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c315859da0f8a0ff183a7f7bf72ba2ce6042ced9105ca3a3e71b366bcc73a6185de1fcd8283b31dec3972e013a6e54f456eb8b850e52e69eb3e35261a31a6a84", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/de97d9d83c2475531f2a9ab76aa66e5fa5db73233c2d0510055df804c4c1078f5e277311832c4465cdac5fab8438abfe7a27c276e34d769c1e24050f9526c0fb"} 22:52:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:52:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:52:45 INFO - Contents: 22:52:45 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/370f1b44c507bed09644536520a1b2ae033b4f43b6fc14ad4e5fa236baeb83a26bebf1f7bc415d5cb67a3bc0b2bed13d875600a08c07289ad868dba436dcffee", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2e29d3dce5018e5525ce67b804e1f0802ab72ac6ae8b597f0f124071f631f70d019ba336fa96ebf22ba0438e949bb0287bf2ce1fcc4d7fca49594dbdaacb8806", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c315859da0f8a0ff183a7f7bf72ba2ce6042ced9105ca3a3e71b366bcc73a6185de1fcd8283b31dec3972e013a6e54f456eb8b850e52e69eb3e35261a31a6a84", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/de97d9d83c2475531f2a9ab76aa66e5fa5db73233c2d0510055df804c4c1078f5e277311832c4465cdac5fab8438abfe7a27c276e34d769c1e24050f9526c0fb"} 22:52:45 INFO - Running post-run listener: copy_logs_to_upload_dir 22:52:45 INFO - Copying logs to upload dir... 22:52:45 INFO - mkdir: /builds/slave/test/build/upload/logs 22:52:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1248.948338 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2017-04-02 22:52:45.642483) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-02 22:52:45.648363) ========= 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.5sSjplHfYa/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.0Q39PTyNsa/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/370f1b44c507bed09644536520a1b2ae033b4f43b6fc14ad4e5fa236baeb83a26bebf1f7bc415d5cb67a3bc0b2bed13d875600a08c07289ad868dba436dcffee", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2e29d3dce5018e5525ce67b804e1f0802ab72ac6ae8b597f0f124071f631f70d019ba336fa96ebf22ba0438e949bb0287bf2ce1fcc4d7fca49594dbdaacb8806", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c315859da0f8a0ff183a7f7bf72ba2ce6042ced9105ca3a3e71b366bcc73a6185de1fcd8283b31dec3972e013a6e54f456eb8b850e52e69eb3e35261a31a6a84", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/de97d9d83c2475531f2a9ab76aa66e5fa5db73233c2d0510055df804c4c1078f5e277311832c4465cdac5fab8438abfe7a27c276e34d769c1e24050f9526c0fb"} build_url:https://queue.taskcluster.net/v1/task/OiK5arCoR0Cq1gy5tOSAvg/artifacts/public/build/firefox-53.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011045 build_url: 'https://queue.taskcluster.net/v1/task/OiK5arCoR0Cq1gy5tOSAvg/artifacts/public/build/firefox-53.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/370f1b44c507bed09644536520a1b2ae033b4f43b6fc14ad4e5fa236baeb83a26bebf1f7bc415d5cb67a3bc0b2bed13d875600a08c07289ad868dba436dcffee", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2e29d3dce5018e5525ce67b804e1f0802ab72ac6ae8b597f0f124071f631f70d019ba336fa96ebf22ba0438e949bb0287bf2ce1fcc4d7fca49594dbdaacb8806", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c315859da0f8a0ff183a7f7bf72ba2ce6042ced9105ca3a3e71b366bcc73a6185de1fcd8283b31dec3972e013a6e54f456eb8b850e52e69eb3e35261a31a6a84", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/de97d9d83c2475531f2a9ab76aa66e5fa5db73233c2d0510055df804c4c1078f5e277311832c4465cdac5fab8438abfe7a27c276e34d769c1e24050f9526c0fb"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-02 22:52:45.683530) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-02 22:52:45.683891) ========= 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.5sSjplHfYa/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.0Q39PTyNsa/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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.004876 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-02 22:52:45.733078) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-02 22:52:45.733443) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-02 22:52:45.733825) ========= ========= Total master_lag: 0.19 =========